builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-049 starttime: 1484322659.12 results: success (0) revision: aa19a56f87c832358a393999875e29d5b5f9618d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.117376) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.117920) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.118251) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.132772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.133103) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-13 07:50:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.4M=0s 2017-01-13 07:50:59 (66.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.184486 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.331214) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.331551) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013783 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 07:50:59.391848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-13 07:50:59.392189) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev aa19a56f87c832358a393999875e29d5b5f9618d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev aa19a56f87c832358a393999875e29d5b5f9618d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-13 07:51:00,077 truncating revision to first 12 chars 2017-01-13 07:51:00,077 Setting DEBUG logging. 2017-01-13 07:51:00,077 attempt 1/10 2017-01-13 07:51:00,077 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/aa19a56f87c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 07:51:02,231 unpacking tar archive at: graphics-aa19a56f87c8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.557173 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-13 07:51:01.962797) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 07:51:01.963116) ========= script_repo_revision: aa19a56f87c832358a393999875e29d5b5f9618d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 07:51:01.965372) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 07:51:01.965643) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 07:51:01.976416) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-01-13 07:51:01.976832) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:51:02 INFO - MultiFileLogger online at 20170113 07:51:02 in /builds/slave/test 07:51:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 07:51:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:51:02 INFO - {'append_to_log': False, 07:51:02 INFO - 'base_work_dir': '/builds/slave/test', 07:51:02 INFO - 'blob_upload_branch': 'Graphics', 07:51:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:51:02 INFO - 'branch': 'Graphics', 07:51:02 INFO - 'buildbot_json_path': 'buildprops.json', 07:51:02 INFO - 'config_files': ('talos/linux_config.py',), 07:51:02 INFO - 'default_actions': ('clobber', 07:51:02 INFO - 'read-buildbot-config', 07:51:02 INFO - 'download-and-extract', 07:51:02 INFO - 'populate-webroot', 07:51:02 INFO - 'create-virtualenv', 07:51:02 INFO - 'install', 07:51:02 INFO - 'run-tests'), 07:51:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:51:02 INFO - 'download_minidump_stackwalk': True, 07:51:02 INFO - 'download_symbols': 'ondemand', 07:51:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:51:02 INFO - 'tooltool.py': '/tools/tooltool.py', 07:51:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:51:02 INFO - '/tools/misc-python/virtualenv.py')}, 07:51:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:51:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:51:02 INFO - 'installer_path': 'installer.exe', 07:51:02 INFO - 'log_level': 'info', 07:51:02 INFO - 'log_name': 'talos', 07:51:02 INFO - 'log_to_console': True, 07:51:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:51:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:51:02 INFO - 'opt_config_files': (), 07:51:02 INFO - 'pip_index': False, 07:51:02 INFO - 'sps_profile': False, 07:51:02 INFO - 'sps_profile_interval': 0, 07:51:02 INFO - 'suite': 'dromaeojs', 07:51:02 INFO - 'system_bits': '32', 07:51:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:51:02 INFO - 'title': 'talos-linux64-ix-049', 07:51:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:51:02 INFO - 'use_talos_json': True, 07:51:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:51:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:51:02 INFO - 'work_dir': 'build'} 07:51:02 INFO - [mozharness: 2017-01-13 15:51:02.758860Z] Running clobber step. 07:51:02 INFO - Running pre-action listener: _resource_record_pre_action 07:51:02 INFO - Running main action method: clobber 07:51:02 INFO - rmtree: /builds/slave/test/build 07:51:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:51:03 INFO - Running post-action listener: _resource_record_post_action 07:51:03 INFO - [mozharness: 2017-01-13 15:51:03.208079Z] Finished clobber step (success) 07:51:03 INFO - [mozharness: 2017-01-13 15:51:03.208181Z] Running read-buildbot-config step. 07:51:03 INFO - Running pre-action listener: _resource_record_pre_action 07:51:03 INFO - Running main action method: read_buildbot_config 07:51:03 INFO - Using buildbot properties: 07:51:03 INFO - { 07:51:03 INFO - "project": "", 07:51:03 INFO - "product": "firefox", 07:51:03 INFO - "who": "kgupta@mozilla.com", 07:51:03 INFO - "installer_path": "public/build/target.tar.bz2", 07:51:03 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 07:51:03 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 07:51:03 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 07:51:03 INFO - "stage_platform": "linux64", 07:51:03 INFO - "basedir": "/builds/slave/test", 07:51:03 INFO - "buildnumber": 52, 07:51:03 INFO - "platform": "ubuntu64_hw", 07:51:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:51:03 INFO - "slavebuilddir": "test", 07:51:03 INFO - "taskId": "T8iG7g2yQeiS-bagi2I0oQ", 07:51:03 INFO - "branch": "graphics", 07:51:03 INFO - "script_repo_revision": "default", 07:51:03 INFO - "revision": "aa19a56f87c832358a393999875e29d5b5f9618d", 07:51:03 INFO - "slavename": "talos-linux64-ix-049", 07:51:03 INFO - "repo_path": "projects/graphics" 07:51:03 INFO - } 07:51:03 INFO - Finding installer, test and symbols from parent task. 07:51:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T8iG7g2yQeiS-bagi2I0oQ'}, attempt #1 07:51:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T8iG7g2yQeiS-bagi2I0oQ'}, attempt #1 07:51:04 INFO - Task dependencies: VYATu1urTp-IjwRBJmvXZQ 07:51:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ'}, attempt #1 07:51:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2 07:51:04 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/test_packages.json 07:51:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.crashreporter-symbols.zip 07:51:04 INFO - Running post-action listener: _resource_record_post_action 07:51:04 INFO - [mozharness: 2017-01-13 15:51:04.608744Z] Finished read-buildbot-config step (success) 07:51:04 INFO - [mozharness: 2017-01-13 15:51:04.608963Z] Running download-and-extract step. 07:51:04 INFO - Running pre-action listener: _resource_record_pre_action 07:51:04 INFO - Running main action method: download_and_extract 07:51:04 INFO - mkdir: /builds/slave/test/build/tests 07:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:04 INFO - trying https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.test_packages.json 07:51:04 INFO - Downloading https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:51:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:51:05 INFO - Downloaded 896 bytes. 07:51:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:51:05 INFO - Using the following test package requirements: 07:51:05 INFO - {u'common': [u'target.common.tests.zip'], 07:51:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:51:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:51:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:51:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:51:05 INFO - u'mozbase': [u'target.common.tests.zip'], 07:51:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:51:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:51:05 INFO - u'web-platform': [u'target.common.tests.zip', 07:51:05 INFO - u'target.web-platform.tests.zip'], 07:51:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:51:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:51:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.common.tests.zip 07:51:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:51:05 INFO - Fetch https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.common.tests.zip into memory 07:51:05 INFO - Expected file size: 20260791 07:51:05 INFO - Obtained file size: 20260791 07:51:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:51:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.common.tests.zip 07:51:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:51:09 INFO - Fetch https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.common.tests.zip into memory 07:51:10 INFO - Expected file size: 20260791 07:51:10 INFO - Obtained file size: 20260791 07:51:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.talos.tests.zip 07:51:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:51:15 INFO - Fetch https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.talos.tests.zip into memory 07:51:15 INFO - Expected file size: 14403532 07:51:15 INFO - Obtained file size: 14403532 07:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:16 INFO - trying https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2 07:51:16 INFO - Downloading https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:51:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:51:17 INFO - Downloaded 56128382 bytes. 07:51:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2 07:51:17 INFO - mkdir: /builds/slave/test/properties 07:51:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:51:17 INFO - Writing to file /builds/slave/test/properties/build_url 07:51:17 INFO - Contents: 07:51:17 INFO - build_url:https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2 07:51:17 INFO - Running post-action listener: _resource_record_post_action 07:51:17 INFO - Running post-action listener: set_extra_try_arguments 07:51:17 INFO - [mozharness: 2017-01-13 15:51:17.201535Z] Finished download-and-extract step (success) 07:51:17 INFO - [mozharness: 2017-01-13 15:51:17.201632Z] Running populate-webroot step. 07:51:17 INFO - Running pre-action listener: _resource_record_pre_action 07:51:17 INFO - Running main action method: populate_webroot 07:51:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:51:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:51:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:51:17 INFO - '--tppagecycles', 07:51:17 INFO - '7'], 07:51:17 INFO - 'tests': ['tsvgm']}}, 07:51:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:51:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:51:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:51:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:51:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:51:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:51:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['damp', 'tps']}, 07:51:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'tests': ['damp', 'tps']}, 07:51:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['dromaeo_dom']}, 07:51:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:51:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['basic_compositor_video']}, 07:51:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:51:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['a11yr', 07:51:17 INFO - 'ts_paint', 07:51:17 INFO - 'tpaint', 07:51:17 INFO - 'sessionrestore', 07:51:17 INFO - 'sessionrestore_no_auto_restore', 07:51:17 INFO - 'tabpaint']}, 07:51:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:51:17 INFO - 'ts_paint', 07:51:17 INFO - 'tpaint', 07:51:17 INFO - 'sessionrestore', 07:51:17 INFO - 'sessionrestore_no_auto_restore', 07:51:17 INFO - 'tabpaint']}, 07:51:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:51:17 INFO - 'ts_paint', 07:51:17 INFO - 'tpaint', 07:51:17 INFO - 'sessionrestore', 07:51:17 INFO - 'sessionrestore_no_auto_restore', 07:51:17 INFO - 'tabpaint']}, 07:51:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:51:17 INFO - 'ts_paint', 07:51:17 INFO - 'tpaint', 07:51:17 INFO - 'sessionrestore', 07:51:17 INFO - 'sessionrestore_no_auto_restore', 07:51:17 INFO - 'tabpaint']}, 07:51:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['a11yr', 07:51:17 INFO - 'ts_paint', 07:51:17 INFO - 'tpaint', 07:51:17 INFO - 'sessionrestore', 07:51:17 INFO - 'sessionrestore_no_auto_restore', 07:51:17 INFO - 'tabpaint']}, 07:51:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['a11yr', 07:51:17 INFO - 'ts_paint', 07:51:17 INFO - 'tpaint', 07:51:17 INFO - 'sessionrestore', 07:51:17 INFO - 'sessionrestore_no_auto_restore', 07:51:17 INFO - 'tabpaint']}, 07:51:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['tsvgx', 07:51:17 INFO - 'tsvgr_opacity', 07:51:17 INFO - 'tart', 07:51:17 INFO - 'tscrollx', 07:51:17 INFO - 'cart', 07:51:17 INFO - 'tsvg_static']}, 07:51:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:51:17 INFO - 'tsvgr_opacity', 07:51:17 INFO - 'tart', 07:51:17 INFO - 'tscrollx', 07:51:17 INFO - 'cart', 07:51:17 INFO - 'tsvg_static']}, 07:51:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'talos_options': ['--disable-e10s'], 07:51:17 INFO - 'tests': ['tp5o']}, 07:51:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:51:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'talos_options': ['--disable-e10s', 07:51:17 INFO - '--xperf_path', 07:51:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:51:17 INFO - 'tests': ['tp5n']}, 07:51:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:51:17 INFO - 'talos_options': ['--xperf_path', 07:51:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:51:17 INFO - 'tests': ['tp5n']}}, 07:51:17 INFO - 'talos.zip': {'path': '', 07:51:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:51:17 INFO - Running post-action listener: _resource_record_post_action 07:51:17 INFO - [mozharness: 2017-01-13 15:51:17.214782Z] Finished populate-webroot step (success) 07:51:17 INFO - [mozharness: 2017-01-13 15:51:17.214876Z] Running create-virtualenv step. 07:51:17 INFO - Running pre-action listener: _resource_record_pre_action 07:51:17 INFO - Running main action method: create_virtualenv 07:51:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:51:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:51:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:51:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:51:17 INFO - Using real prefix '/usr' 07:51:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:51:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:51:19 INFO - Installing pip.................done. 07:51:19 INFO - Return code: 0 07:51:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:51:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:51:20 INFO - Reading from file tmpfile_stdout 07:51:20 INFO - Output received: 07:51:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:51:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:51:20 INFO - Using env: {'DISPLAY': ':0', 07:51:20 INFO - 'HOME': '/home/cltbld', 07:51:20 INFO - 'LANG': 'en_US.UTF-8', 07:51:20 INFO - 'LANGUAGE': 'en_US:en', 07:51:20 INFO - 'LOGNAME': 'cltbld', 07:51:20 INFO - 'MAIL': '/var/mail/cltbld', 07:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:20 INFO - 'MOZ_NO_REMOTE': '1', 07:51:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:20 INFO - 'NO_EM_RESTART': '1', 07:51:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:20 INFO - 'PWD': '/builds/slave/test', 07:51:20 INFO - 'SHELL': '/bin/bash', 07:51:20 INFO - 'SHLVL': '1', 07:51:20 INFO - 'TERM': 'linux', 07:51:20 INFO - 'TMOUT': '86400', 07:51:20 INFO - 'USER': 'cltbld', 07:51:20 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:20 INFO - '_': '/tools/buildbot/bin/python'} 07:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:51:20 INFO - Cleaning up... 07:51:20 INFO - Return code: 0 07:51:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:51:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:51:20 INFO - Using env: {'DISPLAY': ':0', 07:51:20 INFO - 'HOME': '/home/cltbld', 07:51:20 INFO - 'LANG': 'en_US.UTF-8', 07:51:20 INFO - 'LANGUAGE': 'en_US:en', 07:51:20 INFO - 'LOGNAME': 'cltbld', 07:51:20 INFO - 'MAIL': '/var/mail/cltbld', 07:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:20 INFO - 'MOZ_NO_REMOTE': '1', 07:51:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:20 INFO - 'NO_EM_RESTART': '1', 07:51:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:20 INFO - 'PWD': '/builds/slave/test', 07:51:20 INFO - 'SHELL': '/bin/bash', 07:51:20 INFO - 'SHLVL': '1', 07:51:20 INFO - 'TERM': 'linux', 07:51:20 INFO - 'TMOUT': '86400', 07:51:20 INFO - 'USER': 'cltbld', 07:51:20 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:20 INFO - '_': '/tools/buildbot/bin/python'} 07:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:20 INFO - Downloading/unpacking psutil>=3.1.1 07:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:51:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:51:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:51:22 INFO - Installing collected packages: psutil 07:51:22 INFO - Running setup.py install for psutil 07:51:22 INFO - building 'psutil._psutil_linux' extension 07:51:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:51:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:51:22 INFO - building 'psutil._psutil_posix' extension 07:51:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:51:23 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:51:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:51:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:51:23 INFO - Successfully installed psutil 07:51:23 INFO - Cleaning up... 07:51:23 INFO - Return code: 0 07:51:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:51:23 INFO - Using env: {'DISPLAY': ':0', 07:51:23 INFO - 'HOME': '/home/cltbld', 07:51:23 INFO - 'LANG': 'en_US.UTF-8', 07:51:23 INFO - 'LANGUAGE': 'en_US:en', 07:51:23 INFO - 'LOGNAME': 'cltbld', 07:51:23 INFO - 'MAIL': '/var/mail/cltbld', 07:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:23 INFO - 'MOZ_NO_REMOTE': '1', 07:51:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:23 INFO - 'NO_EM_RESTART': '1', 07:51:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:23 INFO - 'PWD': '/builds/slave/test', 07:51:23 INFO - 'SHELL': '/bin/bash', 07:51:23 INFO - 'SHLVL': '1', 07:51:23 INFO - 'TERM': 'linux', 07:51:23 INFO - 'TMOUT': '86400', 07:51:23 INFO - 'USER': 'cltbld', 07:51:23 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:23 INFO - '_': '/tools/buildbot/bin/python'} 07:51:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:51:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:51:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:51:25 INFO - Installing collected packages: mozsystemmonitor 07:51:25 INFO - Running setup.py install for mozsystemmonitor 07:51:25 INFO - Successfully installed mozsystemmonitor 07:51:25 INFO - Cleaning up... 07:51:25 INFO - Return code: 0 07:51:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:51:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:51:25 INFO - Using env: {'DISPLAY': ':0', 07:51:25 INFO - 'HOME': '/home/cltbld', 07:51:25 INFO - 'LANG': 'en_US.UTF-8', 07:51:25 INFO - 'LANGUAGE': 'en_US:en', 07:51:25 INFO - 'LOGNAME': 'cltbld', 07:51:25 INFO - 'MAIL': '/var/mail/cltbld', 07:51:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:25 INFO - 'MOZ_NO_REMOTE': '1', 07:51:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:25 INFO - 'NO_EM_RESTART': '1', 07:51:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:25 INFO - 'PWD': '/builds/slave/test', 07:51:25 INFO - 'SHELL': '/bin/bash', 07:51:25 INFO - 'SHLVL': '1', 07:51:25 INFO - 'TERM': 'linux', 07:51:25 INFO - 'TMOUT': '86400', 07:51:25 INFO - 'USER': 'cltbld', 07:51:25 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:25 INFO - '_': '/tools/buildbot/bin/python'} 07:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:25 INFO - Downloading/unpacking jsonschema==2.5.1 07:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:51:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:28 INFO - Downloading functools32-3.2.3-2.tar.gz 07:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:51:28 INFO - warning: no files found matching '*.txt' 07:51:28 INFO - no previously-included directories found matching 'build' 07:51:28 INFO - no previously-included directories found matching 'dist' 07:51:28 INFO - no previously-included directories found matching '.git*' 07:51:28 INFO - Installing collected packages: jsonschema, functools32 07:51:28 INFO - Running setup.py install for functools32 07:51:28 INFO - warning: no files found matching '*.txt' 07:51:28 INFO - no previously-included directories found matching 'build' 07:51:28 INFO - no previously-included directories found matching 'dist' 07:51:28 INFO - no previously-included directories found matching '.git*' 07:51:28 INFO - Successfully installed jsonschema functools32 07:51:28 INFO - Cleaning up... 07:51:28 INFO - Return code: 0 07:51:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:51:28 INFO - Using env: {'DISPLAY': ':0', 07:51:28 INFO - 'HOME': '/home/cltbld', 07:51:28 INFO - 'LANG': 'en_US.UTF-8', 07:51:28 INFO - 'LANGUAGE': 'en_US:en', 07:51:28 INFO - 'LOGNAME': 'cltbld', 07:51:28 INFO - 'MAIL': '/var/mail/cltbld', 07:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:28 INFO - 'MOZ_NO_REMOTE': '1', 07:51:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:28 INFO - 'NO_EM_RESTART': '1', 07:51:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:28 INFO - 'PWD': '/builds/slave/test', 07:51:28 INFO - 'SHELL': '/bin/bash', 07:51:28 INFO - 'SHLVL': '1', 07:51:28 INFO - 'TERM': 'linux', 07:51:28 INFO - 'TMOUT': '86400', 07:51:28 INFO - 'USER': 'cltbld', 07:51:28 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:28 INFO - '_': '/tools/buildbot/bin/python'} 07:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:51:28 INFO - Cleaning up... 07:51:28 INFO - Return code: 0 07:51:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:51:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:51:28 INFO - Using env: {'DISPLAY': ':0', 07:51:28 INFO - 'HOME': '/home/cltbld', 07:51:28 INFO - 'LANG': 'en_US.UTF-8', 07:51:28 INFO - 'LANGUAGE': 'en_US:en', 07:51:28 INFO - 'LOGNAME': 'cltbld', 07:51:28 INFO - 'MAIL': '/var/mail/cltbld', 07:51:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:28 INFO - 'MOZ_NO_REMOTE': '1', 07:51:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:28 INFO - 'NO_EM_RESTART': '1', 07:51:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:28 INFO - 'PWD': '/builds/slave/test', 07:51:28 INFO - 'SHELL': '/bin/bash', 07:51:28 INFO - 'SHLVL': '1', 07:51:28 INFO - 'TERM': 'linux', 07:51:28 INFO - 'TMOUT': '86400', 07:51:28 INFO - 'USER': 'cltbld', 07:51:28 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:28 INFO - '_': '/tools/buildbot/bin/python'} 07:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:28 INFO - Downloading/unpacking blobuploader==1.2.4 07:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:30 INFO - Downloading blobuploader-1.2.4.tar.gz 07:51:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:51:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:51:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:31 INFO - Downloading docopt-0.6.1.tar.gz 07:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:51:31 INFO - Installing collected packages: blobuploader, requests, docopt 07:51:31 INFO - Running setup.py install for blobuploader 07:51:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:51:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:51:31 INFO - Running setup.py install for requests 07:51:31 INFO - Running setup.py install for docopt 07:51:32 INFO - Successfully installed blobuploader requests docopt 07:51:32 INFO - Cleaning up... 07:51:32 INFO - Return code: 0 07:51:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:51:32 INFO - Using env: {'DISPLAY': ':0', 07:51:32 INFO - 'HOME': '/home/cltbld', 07:51:32 INFO - 'LANG': 'en_US.UTF-8', 07:51:32 INFO - 'LANGUAGE': 'en_US:en', 07:51:32 INFO - 'LOGNAME': 'cltbld', 07:51:32 INFO - 'MAIL': '/var/mail/cltbld', 07:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:32 INFO - 'MOZ_NO_REMOTE': '1', 07:51:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:32 INFO - 'NO_EM_RESTART': '1', 07:51:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:32 INFO - 'PWD': '/builds/slave/test', 07:51:32 INFO - 'SHELL': '/bin/bash', 07:51:32 INFO - 'SHLVL': '1', 07:51:32 INFO - 'TERM': 'linux', 07:51:32 INFO - 'TMOUT': '86400', 07:51:32 INFO - 'USER': 'cltbld', 07:51:32 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:32 INFO - '_': '/tools/buildbot/bin/python'} 07:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:51:32 INFO - Running setup.py (path:/tmp/pip-FwsFQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:51:32 INFO - Running setup.py (path:/tmp/pip-4eMDIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:51:32 INFO - Running setup.py (path:/tmp/pip-ksTlD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:51:32 INFO - Running setup.py (path:/tmp/pip-TQEitp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:51:32 INFO - Running setup.py (path:/tmp/pip-uWvgFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:51:32 INFO - Running setup.py (path:/tmp/pip-YwwVRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:51:32 INFO - Running setup.py (path:/tmp/pip-t5p21_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:51:32 INFO - Running setup.py (path:/tmp/pip-ha2tLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:51:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:51:32 INFO - Running setup.py (path:/tmp/pip-FVmTdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:51:33 INFO - Running setup.py (path:/tmp/pip-RQW5Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:51:33 INFO - Running setup.py (path:/tmp/pip-BEsH9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:51:33 INFO - Running setup.py (path:/tmp/pip-q7bXuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:51:33 INFO - Running setup.py (path:/tmp/pip-Up_FWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:51:33 INFO - Running setup.py (path:/tmp/pip-GkpxG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:33 INFO - Running setup.py (path:/tmp/pip-DN1spI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:51:33 INFO - Running setup.py (path:/tmp/pip-0EPbLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:51:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:51:33 INFO - Running setup.py (path:/tmp/pip-vX_Mxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:51:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:51:33 INFO - Running setup.py install for manifestparser 07:51:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:51:33 INFO - Running setup.py install for mozcrash 07:51:33 INFO - Running setup.py install for mozdebug 07:51:33 INFO - Running setup.py install for mozdevice 07:51:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for mozfile 07:51:34 INFO - Running setup.py install for mozhttpd 07:51:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for mozinfo 07:51:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for mozInstall 07:51:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for mozleak 07:51:34 INFO - Running setup.py install for mozlog 07:51:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for moznetwork 07:51:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for mozprocess 07:51:34 INFO - Running setup.py install for mozprofile 07:51:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:51:34 INFO - Running setup.py install for mozrunner 07:51:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:51:35 INFO - Running setup.py install for mozscreenshot 07:51:35 INFO - Running setup.py install for moztest 07:51:35 INFO - Running setup.py install for mozversion 07:51:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:51:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:51:35 INFO - Cleaning up... 07:51:35 INFO - Return code: 0 07:51:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:51:35 INFO - Using env: {'DISPLAY': ':0', 07:51:35 INFO - 'HOME': '/home/cltbld', 07:51:35 INFO - 'LANG': 'en_US.UTF-8', 07:51:35 INFO - 'LANGUAGE': 'en_US:en', 07:51:35 INFO - 'LOGNAME': 'cltbld', 07:51:35 INFO - 'MAIL': '/var/mail/cltbld', 07:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:35 INFO - 'MOZ_NO_REMOTE': '1', 07:51:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:35 INFO - 'NO_EM_RESTART': '1', 07:51:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:35 INFO - 'PWD': '/builds/slave/test', 07:51:35 INFO - 'SHELL': '/bin/bash', 07:51:35 INFO - 'SHLVL': '1', 07:51:35 INFO - 'TERM': 'linux', 07:51:35 INFO - 'TMOUT': '86400', 07:51:35 INFO - 'USER': 'cltbld', 07:51:35 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:35 INFO - '_': '/tools/buildbot/bin/python'} 07:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:51:35 INFO - Running setup.py (path:/tmp/pip-xd_4lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:51:35 INFO - Running setup.py (path:/tmp/pip-0IxI5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:51:35 INFO - Running setup.py (path:/tmp/pip-b2oIqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:51:35 INFO - Running setup.py (path:/tmp/pip-sO2ajJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:51:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:51:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:51:35 INFO - Running setup.py (path:/tmp/pip-wh8J3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:51:36 INFO - Running setup.py (path:/tmp/pip-3HcqPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:51:36 INFO - Running setup.py (path:/tmp/pip-CTIDI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:51:36 INFO - Running setup.py (path:/tmp/pip-XfkX2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:51:36 INFO - Running setup.py (path:/tmp/pip-R3yf30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:51:36 INFO - Running setup.py (path:/tmp/pip-cNHexY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:51:36 INFO - Running setup.py (path:/tmp/pip-FWGp3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:51:36 INFO - Running setup.py (path:/tmp/pip-PpBdxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:51:36 INFO - Running setup.py (path:/tmp/pip-tv0hlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:51:36 INFO - Running setup.py (path:/tmp/pip-i7u1jl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:36 INFO - Running setup.py (path:/tmp/pip-8bWDoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:51:36 INFO - Running setup.py (path:/tmp/pip-AURJwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:51:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:51:36 INFO - Running setup.py (path:/tmp/pip-QGK82e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:51:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:38 INFO - Downloading blessings-1.6.tar.gz 07:51:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:51:38 INFO - Installing collected packages: blessings 07:51:38 INFO - Running setup.py install for blessings 07:51:39 INFO - Successfully installed blessings 07:51:39 INFO - Cleaning up... 07:51:39 INFO - Return code: 0 07:51:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:51:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:51:39 INFO - Reading from file tmpfile_stdout 07:51:39 INFO - Current package versions: 07:51:39 INFO - argparse == 1.2.1 07:51:39 INFO - blessings == 1.6 07:51:39 INFO - blobuploader == 1.2.4 07:51:39 INFO - docopt == 0.6.1 07:51:39 INFO - functools32 == 3.2.3-2 07:51:39 INFO - jsonschema == 2.5.1 07:51:39 INFO - manifestparser == 1.1 07:51:39 INFO - mozInstall == 1.12 07:51:39 INFO - mozcrash == 1.0 07:51:39 INFO - mozdebug == 0.1 07:51:39 INFO - mozdevice == 0.49 07:51:39 INFO - mozfile == 1.2 07:51:39 INFO - mozhttpd == 0.7 07:51:39 INFO - mozinfo == 0.9 07:51:39 INFO - mozleak == 0.1 07:51:39 INFO - mozlog == 3.4 07:51:39 INFO - moznetwork == 0.27 07:51:39 INFO - mozprocess == 0.24 07:51:39 INFO - mozprofile == 0.28 07:51:39 INFO - mozrunner == 6.13 07:51:39 INFO - mozscreenshot == 0.1 07:51:39 INFO - mozsystemmonitor == 0.3 07:51:39 INFO - moztest == 0.8 07:51:39 INFO - mozversion == 1.4 07:51:39 INFO - psutil == 3.1.1 07:51:39 INFO - requests == 1.2.3 07:51:39 INFO - wsgiref == 0.1.2 07:51:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:51:39 INFO - Using env: {'DISPLAY': ':0', 07:51:39 INFO - 'HOME': '/home/cltbld', 07:51:39 INFO - 'LANG': 'en_US.UTF-8', 07:51:39 INFO - 'LANGUAGE': 'en_US:en', 07:51:39 INFO - 'LOGNAME': 'cltbld', 07:51:39 INFO - 'MAIL': '/var/mail/cltbld', 07:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:39 INFO - 'MOZ_NO_REMOTE': '1', 07:51:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:39 INFO - 'NO_EM_RESTART': '1', 07:51:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:39 INFO - 'PWD': '/builds/slave/test', 07:51:39 INFO - 'SHELL': '/bin/bash', 07:51:39 INFO - 'SHLVL': '1', 07:51:39 INFO - 'TERM': 'linux', 07:51:39 INFO - 'TMOUT': '86400', 07:51:39 INFO - 'USER': 'cltbld', 07:51:39 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:39 INFO - '_': '/tools/buildbot/bin/python'} 07:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:51:39 INFO - Cleaning up... 07:51:39 INFO - Return code: 0 07:51:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a81a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c26c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:51:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:51:39 INFO - Using env: {'DISPLAY': ':0', 07:51:39 INFO - 'HOME': '/home/cltbld', 07:51:39 INFO - 'LANG': 'en_US.UTF-8', 07:51:39 INFO - 'LANGUAGE': 'en_US:en', 07:51:39 INFO - 'LOGNAME': 'cltbld', 07:51:39 INFO - 'MAIL': '/var/mail/cltbld', 07:51:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:39 INFO - 'MOZ_NO_REMOTE': '1', 07:51:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:39 INFO - 'NO_EM_RESTART': '1', 07:51:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:39 INFO - 'PWD': '/builds/slave/test', 07:51:39 INFO - 'SHELL': '/bin/bash', 07:51:39 INFO - 'SHLVL': '1', 07:51:39 INFO - 'TERM': 'linux', 07:51:39 INFO - 'TMOUT': '86400', 07:51:39 INFO - 'USER': 'cltbld', 07:51:39 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:39 INFO - '_': '/tools/buildbot/bin/python'} 07:51:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:51:39 INFO - Cleaning up... 07:51:39 INFO - Return code: 0 07:51:39 INFO - Running post-action listener: _resource_record_post_action 07:51:39 INFO - Running post-action listener: _start_resource_monitoring 07:51:39 INFO - Starting resource monitoring. 07:51:39 INFO - [mozharness: 2017-01-13 15:51:39.885240Z] Finished create-virtualenv step (success) 07:51:39 INFO - [mozharness: 2017-01-13 15:51:39.885739Z] Running install step. 07:51:39 INFO - Running pre-action listener: _resource_record_pre_action 07:51:39 INFO - Running main action method: install 07:51:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:51:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:51:40 INFO - Reading from file tmpfile_stdout 07:51:40 INFO - Detecting whether we're running mozinstall >=1.0... 07:51:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:51:40 INFO - Reading from file tmpfile_stdout 07:51:40 INFO - Output received: 07:51:40 INFO - Usage: mozinstall [options] installer 07:51:40 INFO - Options: 07:51:40 INFO - -h, --help show this help message and exit 07:51:40 INFO - -d DEST, --destination=DEST 07:51:40 INFO - Directory to install application into. [default: 07:51:40 INFO - "/builds/slave/test"] 07:51:40 INFO - --app=APP Application being installed. [default: firefox] 07:51:40 INFO - mkdir: /builds/slave/test/build/application 07:51:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:51:56 INFO - Reading from file tmpfile_stdout 07:51:56 INFO - Output received: 07:51:56 INFO - /builds/slave/test/build/application/firefox/firefox 07:51:56 INFO - Running post-action listener: _resource_record_post_action 07:51:56 INFO - [mozharness: 2017-01-13 15:51:56.413696Z] Finished install step (success) 07:51:56 INFO - [mozharness: 2017-01-13 15:51:56.413975Z] Running run-tests step. 07:51:56 INFO - Running pre-action listener: _resource_record_pre_action 07:51:56 INFO - Running main action method: run_tests 07:51:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:51:56 INFO - Python 2.7.3 07:51:56 INFO - Return code: 0 07:51:56 INFO - grabbing minidump binary from tooltool 07:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c243f0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c24cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c251a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:51:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:51:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:51:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:51:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:51:56 INFO - Return code: 0 07:51:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:51:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:51:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:51:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:51:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:51:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:51:56 INFO - Using env: {'DISPLAY': ':0', 07:51:56 INFO - 'HOME': '/home/cltbld', 07:51:56 INFO - 'LANG': 'en_US.UTF-8', 07:51:56 INFO - 'LANGUAGE': 'en_US:en', 07:51:56 INFO - 'LOGNAME': 'cltbld', 07:51:56 INFO - 'MAIL': '/var/mail/cltbld', 07:51:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:51:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:51:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:56 INFO - 'MOZ_NO_REMOTE': '1', 07:51:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:51:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:51:56 INFO - 'NO_EM_RESTART': '1', 07:51:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:51:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:56 INFO - 'PWD': '/builds/slave/test', 07:51:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:51:56 INFO - 'SHELL': '/bin/bash', 07:51:56 INFO - 'SHLVL': '1', 07:51:56 INFO - 'TERM': 'linux', 07:51:56 INFO - 'TMOUT': '86400', 07:51:56 INFO - 'USER': 'cltbld', 07:51:56 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240', 07:51:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:56 INFO - '_': '/tools/buildbot/bin/python'} 07:51:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:51:56 INFO - mozversion application_buildid: 20170113145601 07:51:56 INFO - mozversion application_changeset: aa19a56f87c832358a393999875e29d5b5f9618d 07:51:56 INFO - mozversion application_display_name: Nightly 07:51:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:51:56 INFO - mozversion application_name: Firefox 07:51:56 INFO - mozversion application_remotingname: firefox 07:51:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 07:51:56 INFO - mozversion application_vendor: Mozilla 07:51:56 INFO - mozversion application_version: 53.0a1 07:51:56 INFO - mozversion platform_buildid: 20170113145601 07:51:56 INFO - mozversion platform_changeset: aa19a56f87c832358a393999875e29d5b5f9618d 07:51:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 07:51:56 INFO - mozversion platform_version: 53.0a1 07:51:56 INFO - using testdate: 1484322716 07:51:56 INFO - actual date: 1484322716 07:51:56 INFO - starting webserver on 'localhost:60921' 07:51:56 INFO - SUITE-START | Running 2 tests 07:51:56 INFO - TEST-START | dromaeo_css 07:51:56 INFO - operating with platform_type : linux_ 07:51:56 INFO - Initialising browser for dromaeo_css test... 07:51:56 INFO - TEST-INFO | started process 32650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUOfowL/profile http://localhost:60921/getInfo.html) 07:52:01 INFO - PROCESS | 32650 | __metrics Screen width/height:1600/1200 07:52:01 INFO - PROCESS | 32650 | colorDepth:24 07:52:01 INFO - PROCESS | 32650 | Browser inner width/height: 1024/697 07:52:01 INFO - PROCESS | 32650 | __metrics 07:52:01 INFO - PROCESS | 32650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:52:01 INFO - PROCESS | 32650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:52:02 INFO - PROCESS | 32650 | console.error: PushService: 07:52:02 INFO - PROCESS | 32650 | stateChangeProcessEnqueue: Error transitioning state 07:52:02 INFO - PROCESS | 32650 | UnknownError 07:52:02 INFO - TEST-INFO | 32650: exit 0 07:52:02 INFO - Browser initialized. 07:52:02 INFO - Running cycle 1/1 for dromaeo_css test... 07:52:02 INFO - TEST-INFO | started process 32729 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUOfowL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:14 INFO - PROCESS | 32729 | RSS: Main: 151482368 07:52:14 INFO - PROCESS | 32729 | 07:55:18 INFO - PROCESS | 32729 | Cycle 1(1): loaded http://localhost:60921/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60921/tests/dromaeo/cssquery-ext.html) 07:55:20 INFO - PROCESS | 32729 | RSS: Main: 353185792 07:55:20 INFO - PROCESS | 32729 | 07:57:03 INFO - PROCESS | 32729 | ************************* 07:57:03 INFO - PROCESS | 32729 | A coding exception was thrown and uncaught in a Task. 07:57:03 INFO - PROCESS | 32729 | 07:57:03 INFO - PROCESS | 32729 | Full message: ReferenceError: fetch is not defined 07:57:03 INFO - PROCESS | 32729 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:57:03 INFO - PROCESS | 32729 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:57:03 INFO - PROCESS | 32729 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:57:03 INFO - PROCESS | 32729 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:57:03 INFO - PROCESS | 32729 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:57:03 INFO - PROCESS | 32729 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:57:03 INFO - PROCESS | 32729 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:57:03 INFO - PROCESS | 32729 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:57:03 INFO - PROCESS | 32729 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:57:03 INFO - PROCESS | 32729 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:57:03 INFO - PROCESS | 32729 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:57:03 INFO - PROCESS | 32729 | 07:57:03 INFO - PROCESS | 32729 | ************************* 07:58:23 INFO - PROCESS | 32729 | Cycle 1(1): loaded http://localhost:60921/tests/dromaeo/cssquery-ext.html (next: http://localhost:60921/tests/dromaeo/cssquery-jquery.html) 07:58:24 INFO - PROCESS | 32729 | RSS: Main: 245387264 07:58:24 INFO - PROCESS | 32729 | 08:01:26 INFO - PROCESS | 32729 | Cycle 1(1): loaded http://localhost:60921/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60921/tests/dromaeo/cssquery-mootools.html) 08:01:26 INFO - PROCESS | 32729 | RSS: Main: 186474496 08:01:26 INFO - PROCESS | 32729 | 08:04:29 INFO - PROCESS | 32729 | Cycle 1(1): loaded http://localhost:60921/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60921/tests/dromaeo/cssquery-prototype.html) 08:04:29 INFO - PROCESS | 32729 | RSS: Main: 205631488 08:04:29 INFO - PROCESS | 32729 | 08:07:32 INFO - PROCESS | 32729 | Cycle 1(1): loaded http://localhost:60921/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60921/tests/dromaeo/cssquery-yui.html) 08:07:33 INFO - PROCESS | 32729 | RSS: Main: 185044992 08:07:33 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | Cycle 1(1): loaded http://localhost:60921/tests/dromaeo/cssquery-yui.html (next: http://localhost:60921/tests/dromaeo/cssquery-dojo.html) 08:10:36 INFO - PROCESS | 32729 | RSS: Main: 274923520 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | __start_tp_report 08:10:36 INFO - PROCESS | 32729 | _x_x_mozilla_page_load 08:10:36 INFO - PROCESS | 32729 | _x_x_mozilla_page_load_details 08:10:36 INFO - PROCESS | 32729 | |i|pagename|runs| 08:10:36 INFO - PROCESS | 32729 | |0;dojo.html;2594.961075583866;2595.9610605840926;2630.763231309182;2634.973650263498;2636.70996190419;4677.812887484505;4736.100140973213;4805.735684537349;4814.398200224976;4824.204006338954;4688.038952014837;4699.459562150345;4730.526947305268;4786.329913812069;4789.233722604384;4333.544977777336;4349.10843277129;4380.88287486692;4433.069055498349;4499.325101234825;4162.7502349859105;4396.186705459484;4467.17357288901;4484.618807401374;4540.18276710192;4350.782460876943;4384.539623339535;4464.732116073046;4472.508024117345;4533.682642215046;4332.371806088131;4375.518692943773;4388.692791504596;4413.051193993307;4455.799489023002;4370.2133615949115;4373.518912919576;4381.364702118175;4384.7369157850635;4420.04968931678;4697.459792123881;4766.451858036336;4792.616590672737;4797.568218860284;4823.2282834746275;5055.368078990126;5058.620603454756;5100.872478188038;5112.36095488064;5179.974100129513;5144.819931302386;5154.201098829687;5155.510226528474;5164.870878228075;5166.405863325728;4727.881802954885;4812.085703716318;4815.662903596714;4817.927731084036;4859.052484765472;5058.494150584986;5105.9489405106215;5149.897002059938;5155.587552995751;5224.52979231871;4916.287138364917;4926.33494478248;4926.482719314492;4929.3345398371475;5010.49895010496;4937.629677774181;4954.281629163823;5042.495750424928;5055.09008378488;5055.545000949932;4974.129527332702;4985.077760614298;5026.52248036436;5029.924551131736;5110.770015349318;5034.924476132861;5042.294078828967;5042.4957504250015;5042.848714538496;5092.821751238689;4279.7218180818145;4308.0306930940915;4333;4349.847755328548;4384.802683879233;3716.1824398632257;3743.3636281832155;3770.4155855842387;3794.0894185395305;3873.5545412277384;4403.779811009436;4417.2269852775635;4418.5802348776815;4423.20382331177;4455.532169122261;5116.948830511722;5120.8719782005;5122.103631864409;5133.5379815816;5183.067047931333;4337.783110844445;4377.146456440963;4418.248897687401;4462.531434199363;4464.575865292792;4642.628589712815;4659.883502912454;4662.533746625379;4694.50707675696;4699.436067671902;4885.682430641997;4887;4890.999999999929;4894.926576101432;4940.2342636891335;4982.825601103944;5016.423111342242;5073.264376665436;5096.617753668415;5214.113600687968;4808.3027960946165;4830.7101573905675;4841.951580484291;4853.878653033702;4893.070316639827;5055.5955523557295;5056.974715126548;5058.241263810458;5064.594832413324;5093.898122037464;5029.899402012013;5070.062038522885;5075.593952483866;5113.6931784091585;5186.06650802844;4902.534259245366;4989.226669866177;4995.200767877122;4998.550130488128;5016.398032236009;4913.926291105708;4955.479674634255;4956.504349565159;4957.776900039579;5001;4765.833195838268;4768.332433459249;4780.689255198401;4816.759162041813;4849.660523763303;4921;4933.851984440473;4948.777305021211;4963.503649635008;4967.379077615298;4807.302941073455;4841.443234028132;4841.4674385816825;4843.6367272454;4843.782029808596 08:10:36 INFO - PROCESS | 32729 | |1;ext.html;560.3891757983797;567.5487987050296;578.3406916115626;578.8639669677625;627.7583130494758;39888.202235955265;42045.57954420455;42218.577814221884;45666.77166614162;45720;41796.791016044954;42310.576894231046;42346;42416.151676966445;42482.36276455856;7623.390128789683;7761.379089672841;7785.688572457123;7866.41001924855;8044.477108987897;7655.119661238945;10169.186465082776;10378.065974062332;10428.947855260712;10783.352998820057;10491.895081049206;10565.313254638424;10567.26029177958;10602.363858168495;10613;10478.947605261963;10544.15646748266;10552.314099583502;10557.366558006468;10561.207909406749;10357.844632330522;10428.322159059637;10443.738906527322;10445.582176712922;10448.582056717722;38317.808410958045;38654;38772.22455550901;38833;38877.41683874744;3580.1944562473236;3673.522442082512;3680.5215322008235;3712.7029837612927;3735.3751118263644;28225.85887070572;29075;29100.41799164026;29131.708682913322;29181.70818291811;3409.9147521311916;3428.5542879425516;3608.4046132388125;3621.9275614487565;3625.1299688074673;227895;228623;230058;230416.84791576103;231169;52709.7364513175;53047.20429193565;53053.204201937;53096.73451632717;53163.734181328844;55504;55565.72217138889;55648.16527752087;55675;55725.721371393694;12621.242725436503;12926.741465170831;12929.612111636667;12932.159409638343;12940.611781646377;8617.95691021541;8892;9461.432314061178;9659.082387173208;9667.613295468103;14841.257937103102;14985.850141498664;14991.925040374947;16332.918335408485;16689.833101668828;5553.8333849984565;6321.494280457552;6355.491560675135;6376.3623637635865;6442.323556026643;740.3189066059239;827.6914841945725;858.9312854971712;868.1535502884637;881.9426737262057;26375;27534.311642208704;28219.43561128766;28221;28226.294342641188;896.1397058823602;904.4076130134772;914.1589737441569;940.4968341937167;1014.0873214107362;13748.793768093487;14661.706765864623;14725.091319751396;14900.478483253035;15821.841781582267;346214;348302;348758;348815;348964;40146;41091.589084108775;43527.56472435235;44558.331625025654;44726.32910506345;1230.501646833034;1263.6967127889425;1276.3299267884436;1288.9613311600667;1291.928943908094;25368;26182.607260891484;26700.599491007262;26833.329166770985;27053.86473067622;37705;38065;38068.238635227695;38084.238315232986;38091.42862857004;284245;284458;284812;285585;286602;22874.885625571766;22967;23230.535389292458;23326.76673233246;23362.64956025695;33158.502622461165;33380.833095834365;36667.81666091653;36763.44854827233;38119.23761524699;31141.377172456876;31380.529292060182;32238.838805805823;32665.836670816494;33282.16794580155;31254.21864453407;32205.355892881544;32284.677153228167;32553.34893302168;33494.330113398086;31499.52750708787;31502.842485787423;31660.208494786893;32232;32263;27256;29672.258193545335;29886.402271954874;29970;30046;22533.32400028002;22715.31854044381;22877.65683514782;22969.77030229676;22994.540109198057 08:10:36 INFO - PROCESS | 32729 | |2;jquery.html;371.1871002504513;391.9431682406053;395.1602843956592;399.9160176362964;417.6700406678724;12687.936560317197;14333.784993225097;15368.923155384235;18293;18404.907975460137;14826.184559849204;15241.542753717406;15278.694426111471;15331.386744530206;15789.28948197331;4528.275475923861;4741.9525804742025;4742.834000809972;4747.1692453820615;4749.287606858981;3610;3778.8299526521237;4608.1705293047235;4665.9766701166445;6090.056041313604;6215.011196417126;6346.46055085316;6349;6353.205849268841;6354.015127655198;5620.4660557247;5626.662400255997;5660.943390566083;5664.150377443396;6344.3655634436645;5493.23094766733;5494.532964698005;5501.064818980783;5501.807436739735;5503.807366742145;12509.249445033327;17437.47687569375;19203.32788352401;21252.36242912715;21352.5729485411;2124.5538436928264;2213.867167969927;2220.6446968484966;2458.164224163775;2518.597024476075;9257;9347.579358928864;9357.625694972194;9565.808683826353;9768.609255629766;1717.43324702849;1730.954796249098;1759.8592112630959;1760.119940029985;1776.5736223306442;75914.24085759182;76471.61764191199;77531.22468775297;77776.99999999943;79766;10593.629222977159;10662.360258384366;10702.46487675613;10843.457827108614;10911.890881091087;14907.254637268094;14932.25338733059;14936.701265974836;14951.102933824006;14972.47596334123;6756.797296081126;7006.054182685374;7049.3655570997835;7202.1357437106835;7224.349808517259;6237.844053898598;6257.56197066201;6409.55133140685;6452.322506136882;6478.481721462366;4518.819247230074;4550.317452382169;4623.594529524161;4688.390509233778;4731.4795372509;1935.1968932892692;1974.2596526302636;2000.1999200319988;2014.204389266261;2023.9898800505903;759.1762937213185;780.4692808889896;791.6318911706085;793.2820797178563;801.6552882260684;17431.12844357777;17881.642367152843;18072.457826265232;18085.27658893656;18462.630747384977;750.9098908131059;762.771168649403;767.244264399566;773.8735508545526;795.9562224077731;9729.513524323755;9930.304878658424;10025.649102281384;10026.799464010825;10052.69841904744;487639;491280;492290;495712;497353;80123.59938200389;85521.57239213889;86710;87134.56432717796;90829.54585227164;931.3573634192386;966.6761634852403;972.4602845420859;973.2992245583168;973.561897146287;16066.839331607001;16266.268017939457;16672.416465423652;17066.402675906284;17253.482395528154;38018;38794.2241155181;39286.80356598199;40715.59284407118;41534.58465415428;50007.49992500174;51516.48483515117;54481.72759136179;55055;56277.43722562722;7580.886286705593;7636.770896873103;7794;7813.609319533887;7894;9607.567659455202;11661.300321980709;11788.764224715285;12274.570390036306;12762.29807360604;10843.837342440027;10960;11258.549658013915;11270.267432616854;11278;10225.74435639115;10285;10319.174466042849;10416.895831041593;10642;10578.365298082139;10611.734706632087;11147.442627868737;11351.829722554003;11535.365554894226;7579.1284002340435;7733.613319333898;7772.417068719982;7824.1002284735705;8155.918440815516;9407.905920940702;10539;11308;11319.886801132212;11686 08:10:36 INFO - PROCESS | 32729 | |3;mootools.html;1370.1299674706559;1373.375114322983;1374.6494643865813;1375.463569208009;1382.723455308938;6689.5651782634095;7022.192447868497;7022.613756243404;7029.789106326805;7032.226455089936;6266.467350275232;6452.516061295398;6857.931420685792;6904.551204171726;7022.789316320505;2175.7606663267065;2202.2292197730744;2203.7575866654734;2206.558688262346;2207.073029327686;2164.3182397544724;2200.8459407841456;2201.0205458570863;2201.306588424649;2201.4936564590075;1858.7676540432446;1924.595834874678;2083.197968782015;2143.967840482394;2156.7411910570754;2152.569486102778;2158.1691048946113;2159.168720042779;2159.88120653364;2162.0378931375467;2105.4420578546697;2122.6922096295953;2126.5002724359765;2128.30829980257;2130.360891732474;7148.678309476034;7293.234210407883;7296.124465064173;7299.4525410594415;7299.5620262784405;4248.745075295461;4371.803268852909;4383.079553293812;4384.714993525411;4385.232584297767;2815.9859200704072;2972.346083861547;2984.089852594958;2999.3701322722254;3032.757379409642;1019.3170575714215;1070.5664205996584;1081.2539347850036;1085.16442339399;1173.4308860202796;9385.76535586609;9418.905810941871;9431.434113953184;9437.339386242966;9438.150566449052;3935.7244992850797;4209.705320627527;4220.0293932395725;4282.785860706952;4287.249731297011;8253.463524870864;8258.174182581814;8262.297704695167;8264.669413223404;8267.586620669063;4390.473143222834;4394.758288294111;4396.384506169139;4397.362382454527;4399.472063352354;3609.296187243462;3720.4605332226674;3837.5970523094675;3969.980150099289;4052.49343832021;3516.2748869626244;3530.0821786335223;3662.487251784752;3665.9083522911606;3704.5369328833895;1185.9407029648482;1208.3897631695938;1213.4880796750817;1215.1615385384234;1220.1824777399163;818.1491249100868;825.7440193540021;825.9669613215405;833.7873842170208;836.030204962253;4668.066386722601;4745.3593764842;4770.189067858472;4783.210770222944;4992.051510213048;538.6014349381508;539.4982666120467;543.4836904940322;546.4071482441595;556.3240662594967;3034.969650303513;3066.9386612268077;3072.2165847708693;3082.1370016395445;3164.4462219112024;8123.106458289583;8432.367572432093;8442.240198382175;8478.618462169217;8497.915020849836;8367.623456944451;8384.161583841567;8389.874151887727;8391.832163356821;8396.580170991549;6648.933510664831;6744.460443164535;6745.291744366869;6746.190457145174;6748.662566871539;4537.001859591017;4611.400517932647;4618.353430519797;4644.2801365788355;4672.672912896064;7642.5032372895585;7726.575038372944;7757.573333466714;7780.3386712130105;7902.091259505;7793.766187014398;7858.253465920729;7980.600969951363;8046.959765201136;8069.878951815845;6026.487748541423;6086.695665216633;6114;6136.355682653257;6180;6500.5449618526245;6544.411003009752;6555.311692272243;6557.409833115043;6557.508186885907;5855;5947.9702601486715;6123.693815309128;6145.9692701536205;6203.751849925952;5838.562107842014;5851.327097383855;5914.231149950651;5919.437653422918;5946.13781001761;5548.722563871871;5564.415736347625;5605.523530499953;5608.214849921096;5609.775608975595;4220.788960552021;4222.767747773779;4326.178026175016;4329.393884856059;4423.469183697976;4160.563140870286;4163.458750362433;4210.957890421179;4267.16790225903;4310.655147588122 08:10:36 INFO - PROCESS | 32729 | |4;prototype.html;342.8217326989965;347.4587146001757;347.52389226759334;348.38857804553004;358.0404515897396;4698.577128058474;4862.829800956967;4918.139325618016;4931.704097754133;4946.851594452168;4697.506761790014;4824.734639594821;4855.854324370266;4862.805487780493;4863.440704319004;3880.2821478026513;3924.6075392460666;3936.4292177634225;3942.881713548584;3962.385830196317;3696.5009723687226;3919.745216560929;3922.7643292362814;3933.2920074386598;3956.6636835868985;3716.3682174030346;3734.514513113305;3735.1969326594913;3765.3598888188935;3781.470594116826;3744.4008958566633;3750.19370835269;3758.718096142773;3760.1163726524246;3776.169242766587;3293.4730443128983;3296.5549650797075;3296.785708928936;3304.686054824788;3315.292624298488;4676.859694209179;4709.458412282597;4733.455652599926;4759.381280433521;4786.425628924517;4248.957510424887;4297.935530967038;4311.655067594585;4314.82740690372;4318.179545886303;5106.748846532586;5118.38579370478;5118.564921981636;5119.38567371914;5126.846194614168;3523.612402635708;3611.259691763182;3619.7828130312;3622.3660859349507;3623.800690961996;5279.815206467793;5292;5313.814016509442;5350.892982140335;5359.919601205985;3619.5475565554307;3640.5995340512522;3657.158853463718;3661.5972243053243;3665.3585622515952;3662.468942003448;3677.2277821657217;3682.834272457746;3699.1491956850077;3708.721845861571;4180.581941805856;4264.552222016706;4264.594863487963;4288.185244803477;4305.763183024901;5129.256257842592;5140.383154021467;5141.0232055909255;5146.356705411824;5146.713321669583;1208.7945049341633;1227.2268470863298;1229.237872519044;1244.9813252801216;1263.6524955637274;819.942604017725;833.6998680475024;834.7120243515977;843.9831203376021;853.2022558907317;4338.717983331074;4360.19052093384;4385.342198670161;4432.847459660511;4438.71148375361;5127.102757017507;5145;5154.329937108227;5161.406438259573;5176.663516871391;4280.357946308079;4524.411826462539;4576.244919588231;4577.450705915245;4586.128635559233;2741.7806575473915;2743.2181828179273;2744.945101098007;2762.337039110599;2769.778417726577;4766.189747742892;4829.444613869379;4842.346283251786;4903.926441103386;4919.581835544019;5291.708956007379;5299.5230429261555;5299.920501192485;5303.04545181871;5341.385740639798;4827.879303017312;4829.855104346875;4848.80604775805;4866.878328041827;4891.926621100686;4959.20652695567;5064.8987020258655;5101.234814777813;5167.811403377169;5168.173092305213;5035.395752509722;5057.8229761958155;5066.594672426123;5076.7207803571155;5091.949080509147;4807.61539076866;4841.540053694894;4845.103655823684;4852.708837469763;4858.246971719389;3489.2498112905846;3581.7492775505466;3582.5848789727393;3596.964030359663;3621;5088.974555127201;5108.208227724708;5115.4373018967135;5150.6137039721425;5152.690838549699;4964.578010869117;4987.202047672355;5001.899962000813;5007.674501157413;5019.447860735243;4746.572808447257;4988.351514303117;4993.625478089085;5004.4495105537635;5010.49895010496;4789.784459699252;4896.92654610174;4909.214525676018;4909.828156014522;4911.459739428588;5139.229115632685;5141.742912854267;5145.974270128775;5158.561522270649;5165.217999790067;5019.222020586815;5032.672876263031;5038.47096054909;5045.672031317952;5061.392632884077 08:10:36 INFO - PROCESS | 32729 | |5;yui.html;672.3008071605531;683.371298405467;724.1238101896705;732.3591857125016;743.1449577960954;11866.347350895698;12896.677583060427;12919.54781582646;12921.160124591896;12951.805722914152;12629.747405051894;12629.936850315758;12657.62027139185;12701.364931753422;12794.296313702742;646.1470858466826;661.646019379632;778.5562229529171;799.1648727080216;804.6017221475348;815.0260438775665;816.7549735079482;817.9468334558264;818.1981657975188;819.713100414856;861.5907443964129;864.3215076165211;865.523961820999;865.645085514939;866.1771317248607;688.8243497908027;709.9645017749143;711.1253158614933;713.7787285941375;862.5299211929513;614.5329549542336;634.2452481546944;637.2639601260552;638.2436812377348;638.987220255597;13454.05821592489;13753.724925501534;13793.034487585874;13802.447902083904;13810.309484525835;12772.425240864091;12959.352032398343;13018.893394061517;13171.670708232276;13277.867221327857;10513.474326283655;10611.628592999285;10701;10780.676579702622;11043.613473528469;4039.515258169009;4081.1837632473625;4089.7137200395982;4253.319468884994;4259.318509038539;59311.40688593145;60176.69911650414;60835.08747368793;61050.389496104915;61234;9389.201917836925;9463.810723785624;9676.032396760267;9680.419174849532;9725.319227653996;17886.28454861791;17999.73000404995;18047.63904721898;18122.184501697455;18196.363127290482;6391;6400.839979000469;6454.290028096905;6478.481721462272;6654.800355989231;3389.457686770105;3469.7397695172963;3470.4273794824076;3518.0677120563073;3597.8201089945396;2674.41162944152;2710.8102432829905;2728.7953403494816;2739.917802465929;2745.7391547802927;452.50224752771686;459.2903963376591;461.55229427455316;462.60447317543014;466.8622756286903;551.5256879083985;568.3378863623915;570.805925985167;574.7126436781609;583.2534356024296;7596.2023987481625;7813.296803287732;8020.79948001293;8094.854863461187;8200.917990820135;566.8611190258331;567.9034564124026;576.4725276372156;576.7779404929132;580.4688709830542;3953.584873588289;4142.792860357031;4156.7921603919685;4192.392103145088;4269.210196113667;64459.35540644627;65543.67228163828;65828.34171658318;66179;67368;57960.71019644959;58489;59010.704946474994;59194;59257;51096.73678317177;52943.735281323345;53099.73450132724;53795.46204537983;54080.459195407544;7471.476996610185;7709.383249339927;7778.49439786412;7903.407244456574;7927.16764739694;36153.638463615025;36288.63711362852;36717.08207294839;37157.628423716495;37192.25615487621;42027.159456810085;44760.328595071755;44761.776191118835;45037.32444013277;45330.54669453263;9208.493532855757;9293.581788819654;9305.441673499612;9333.813323733351;9446.480443575669;9956.502174891371;9983.301168918397;10012.799744005224;10036.49817509107;10143.949280253551;9179.586918588813;9179.908200917906;9251.907480925105;9413.52932353366;9779.559919803769;9105.317101217568;9165.266778657558;9433.29250306216;9490.28822838276;10289.228307876789;8936.597853096757;8937.687180948635;8941.618537332117;8995.685151019683;8999.910000899907;8385.622646980779;8447.493150410995;8554.529500877512;8659.696910608098;9030.774230644023;9506.305912507005;9527.237820974167;9550.808983820145;9615.9038409615;9658.517074146124 08:10:36 INFO - PROCESS | 32729 | __end_tp_report 08:10:36 INFO - PROCESS | 32729 | __start_cc_report 08:10:36 INFO - PROCESS | 32729 | _x_x_mozilla_cycle_collect,2423 08:10:36 INFO - PROCESS | 32729 | __end_cc_report 08:10:36 INFO - PROCESS | 32729 | __startTimestamp1484323836619__endTimestamp 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | ------- Summary: start ------- 08:10:36 INFO - PROCESS | 32729 | Number of tests: 6 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | [#0] dojo.html Cycles:165 Average:4709.63 Median:4841.47 stddev:489.59 (10.1%) stddev-sans-first:462.14 08:10:36 INFO - PROCESS | 32729 | Values: 2595.0 2596.0 2630.8 2635.0 2636.7 4677.8 4736.1 4805.7 4814.4 4824.2 4688.0 4699.5 4730.5 4786.3 4789.2 4333.5 4349.1 4380.9 4433.1 4499.3 4162.8 4396.2 4467.2 4484.6 4540.2 4350.8 4384.5 4464.7 4472.5 4533.7 4332.4 4375.5 4388.7 4413.1 4455.8 4370.2 4373.5 4381.4 4384.7 4420.0 4697.5 4766.5 4792.6 4797.6 4823.2 5055.4 5058.6 5100.9 5112.4 5180.0 5144.8 5154.2 5155.5 5164.9 5166.4 4727.9 4812.1 4815.7 4817.9 4859.1 5058.5 5105.9 5149.9 5155.6 5224.5 4916.3 4926.3 4926.5 4929.3 5010.5 4937.6 4954.3 5042.5 5055.1 5055.5 4974.1 4985.1 5026.5 5029.9 5110.8 5034.9 5042.3 5042.5 5042.8 5092.8 4279.7 4308.0 4333.0 4349.8 4384.8 3716.2 3743.4 3770.4 3794.1 3873.6 4403.8 4417.2 4418.6 4423.2 4455.5 5116.9 5120.9 5122.1 5133.5 5183.1 4337.8 4377.1 4418.2 4462.5 4464.6 4642.6 4659.9 4662.5 4694.5 4699.4 4885.7 4887.0 4891.0 4894.9 4940.2 4982.8 5016.4 5073.3 5096.6 5214.1 4808.3 4830.7 4842.0 4853.9 4893.1 5055.6 5057.0 5058.2 5064.6 5093.9 5029.9 5070.1 5075.6 5113.7 5186.1 4902.5 4989.2 4995.2 4998.6 5016.4 4913.9 4955.5 4956.5 4957.8 5001.0 4765.8 4768.3 4780.7 4816.8 4849.7 4921.0 4933.9 4948.8 4963.5 4967.4 4807.3 4841.4 4841.5 4843.6 4843.8 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | [#1] ext.html Cycles:180 Average:44045.08 Median:32419.01 stddev:76525.27 (236.1%) stddev-sans-first:76670.29 08:10:36 INFO - PROCESS | 32729 | Values: 560.4 567.5 578.3 578.9 627.8 39888.2 42045.6 42218.6 45666.8 45720.0 41796.8 42310.6 42346.0 42416.2 42482.4 7623.4 7761.4 7785.7 7866.4 8044.5 7655.1 10169.2 10378.1 10428.9 10783.4 10491.9 10565.3 10567.3 10602.4 10613.0 10478.9 10544.2 10552.3 10557.4 10561.2 10357.8 10428.3 10443.7 10445.6 10448.6 38317.8 38654.0 38772.2 38833.0 38877.4 3580.2 3673.5 3680.5 3712.7 3735.4 28225.9 29075.0 29100.4 29131.7 29181.7 3409.9 3428.6 3608.4 3621.9 3625.1 227895.0 228623.0 230058.0 230416.8 231169.0 52709.7 53047.2 53053.2 53096.7 53163.7 55504.0 55565.7 55648.2 55675.0 55725.7 12621.2 12926.7 12929.6 12932.2 12940.6 8618.0 8892.0 9461.4 9659.1 9667.6 14841.3 14985.9 14991.9 16332.9 16689.8 5553.8 6321.5 6355.5 6376.4 6442.3 740.3 827.7 858.9 868.2 881.9 26375.0 27534.3 28219.4 28221.0 28226.3 896.1 904.4 914.2 940.5 1014.1 13748.8 14661.7 14725.1 14900.5 15821.8 346214.0 348302.0 348758.0 348815.0 348964.0 40146.0 41091.6 43527.6 44558.3 44726.3 1230.5 1263.7 1276.3 1289.0 1291.9 25368.0 26182.6 26700.6 26833.3 27053.9 37705.0 38065.0 38068.2 38084.2 38091.4 284245.0 284458.0 284812.0 285585.0 286602.0 22874.9 22967.0 23230.5 23326.8 23362.6 33158.5 33380.8 36667.8 36763.4 38119.2 31141.4 31380.5 32238.8 32665.8 33282.2 31254.2 32205.4 32284.7 32553.3 33494.3 31499.5 31502.8 31660.2 32232.0 32263.0 27256.0 29672.3 29886.4 29970.0 30046.0 22533.3 22715.3 22877.7 22969.8 22994.5 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | [#2] jquery.html Cycles:180 Average:27866.26 Median:4579.24 stddev:81157.14 (1772.3%) stddev-sans-first:81358.55 08:10:36 INFO - PROCESS | 32729 | Values: 371.2 391.9 395.2 399.9 417.7 12687.9 14333.8 15368.9 18293.0 18404.9 14826.2 15241.5 15278.7 15331.4 15789.3 4528.3 4742.0 4742.8 4747.2 4749.3 3610.0 3778.8 4608.2 4666.0 6090.1 6215.0 6346.5 6349.0 6353.2 6354.0 5620.5 5626.7 5660.9 5664.2 6344.4 5493.2 5494.5 5501.1 5501.8 5503.8 12509.2 17437.5 19203.3 21252.4 21352.6 2124.6 2213.9 2220.6 2458.2 2518.6 9257.0 9347.6 9357.6 9565.8 9768.6 1717.4 1731.0 1759.9 1760.1 1776.6 75914.2 76471.6 77531.2 77777.0 79766.0 10593.6 10662.4 10702.5 10843.5 10911.9 14907.3 14932.3 14936.7 14951.1 14972.5 6756.8 7006.1 7049.4 7202.1 7224.3 6237.8 6257.6 6409.6 6452.3 6478.5 4518.8 4550.3 4623.6 4688.4 4731.5 1935.2 1974.3 2000.2 2014.2 2024.0 759.2 780.5 791.6 793.3 801.7 17431.1 17881.6 18072.5 18085.3 18462.6 750.9 762.8 767.2 773.9 796.0 9729.5 9930.3 10025.6 10026.8 10052.7 487639.0 491280.0 492290.0 495712.0 497353.0 80123.6 85521.6 86710.0 87134.6 90829.5 931.4 966.7 972.5 973.3 973.6 16066.8 16266.3 16672.4 17066.4 17253.5 38018.0 38794.2 39286.8 40715.6 41534.6 50007.5 51516.5 54481.7 55055.0 56277.4 7580.9 7636.8 7794.0 7813.6 7894.0 9607.6 11661.3 11788.8 12274.6 12762.3 10843.8 10960.0 11258.5 11270.3 11278.0 10225.7 10285.0 10319.2 10416.9 10642.0 10578.4 10611.7 11147.4 11351.8 11535.4 7579.1 7733.6 7772.4 7824.1 8155.9 9407.9 10539.0 11308.0 11319.9 11686.0 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | [#3] mootools.html Cycles:180 Average:4670.57 Median:4709.02 stddev:2486.68 (52.8%) stddev-sans-first:2481.29 08:10:36 INFO - PROCESS | 32729 | Values: 1370.1 1373.4 1374.6 1375.5 1382.7 6689.6 7022.2 7022.6 7029.8 7032.2 6266.5 6452.5 6857.9 6904.6 7022.8 2175.8 2202.2 2203.8 2206.6 2207.1 2164.3 2200.8 2201.0 2201.3 2201.5 1858.8 1924.6 2083.2 2144.0 2156.7 2152.6 2158.2 2159.2 2159.9 2162.0 2105.4 2122.7 2126.5 2128.3 2130.4 7148.7 7293.2 7296.1 7299.5 7299.6 4248.7 4371.8 4383.1 4384.7 4385.2 2816.0 2972.3 2984.1 2999.4 3032.8 1019.3 1070.6 1081.3 1085.2 1173.4 9385.8 9418.9 9431.4 9437.3 9438.2 3935.7 4209.7 4220.0 4282.8 4287.2 8253.5 8258.2 8262.3 8264.7 8267.6 4390.5 4394.8 4396.4 4397.4 4399.5 3609.3 3720.5 3837.6 3970.0 4052.5 3516.3 3530.1 3662.5 3665.9 3704.5 1185.9 1208.4 1213.5 1215.2 1220.2 818.1 825.7 826.0 833.8 836.0 4668.1 4745.4 4770.2 4783.2 4992.1 538.6 539.5 543.5 546.4 556.3 3035.0 3066.9 3072.2 3082.1 3164.4 8123.1 8432.4 8442.2 8478.6 8497.9 8367.6 8384.2 8389.9 8391.8 8396.6 6648.9 6744.5 6745.3 6746.2 6748.7 4537.0 4611.4 4618.4 4644.3 4672.7 7642.5 7726.6 7757.6 7780.3 7902.1 7793.8 7858.3 7980.6 8047.0 8069.9 6026.5 6086.7 6114.0 6136.4 6180.0 6500.5 6544.4 6555.3 6557.4 6557.5 5855.0 5948.0 6123.7 6146.0 6203.8 5838.6 5851.3 5914.2 5919.4 5946.1 5548.7 5564.4 5605.5 5608.2 5609.8 4220.8 4222.8 4326.2 4329.4 4423.5 4160.6 4163.5 4211.0 4267.2 4310.7 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | [#4] prototype.html Cycles:180 Average:4196.44 Median:4816.18 stddev:1218.00 (25.3%) stddev-sans-first:1186.58 08:10:36 INFO - PROCESS | 32729 | Values: 342.8 347.5 347.5 348.4 358.0 4698.6 4862.8 4918.1 4931.7 4946.9 4697.5 4824.7 4855.9 4862.8 4863.4 3880.3 3924.6 3936.4 3942.9 3962.4 3696.5 3919.7 3922.8 3933.3 3956.7 3716.4 3734.5 3735.2 3765.4 3781.5 3744.4 3750.2 3758.7 3760.1 3776.2 3293.5 3296.6 3296.8 3304.7 3315.3 4676.9 4709.5 4733.5 4759.4 4786.4 4249.0 4297.9 4311.7 4314.8 4318.2 5106.7 5118.4 5118.6 5119.4 5126.8 3523.6 3611.3 3619.8 3622.4 3623.8 5279.8 5292.0 5313.8 5350.9 5359.9 3619.5 3640.6 3657.2 3661.6 3665.4 3662.5 3677.2 3682.8 3699.1 3708.7 4180.6 4264.6 4264.6 4288.2 4305.8 5129.3 5140.4 5141.0 5146.4 5146.7 1208.8 1227.2 1229.2 1245.0 1263.7 819.9 833.7 834.7 844.0 853.2 4338.7 4360.2 4385.3 4432.8 4438.7 5127.1 5145.0 5154.3 5161.4 5176.7 4280.4 4524.4 4576.2 4577.5 4586.1 2741.8 2743.2 2744.9 2762.3 2769.8 4766.2 4829.4 4842.3 4903.9 4919.6 5291.7 5299.5 5299.9 5303.0 5341.4 4827.9 4829.9 4848.8 4866.9 4891.9 4959.2 5064.9 5101.2 5167.8 5168.2 5035.4 5057.8 5066.6 5076.7 5091.9 4807.6 4841.5 4845.1 4852.7 4858.2 3489.2 3581.7 3582.6 3597.0 3621.0 5089.0 5108.2 5115.4 5150.6 5152.7 4964.6 4987.2 5001.9 5007.7 5019.4 4746.6 4988.4 4993.6 5004.4 5010.5 4789.8 4896.9 4909.2 4909.8 4911.5 5139.2 5141.7 5146.0 5158.6 5165.2 5019.2 5032.7 5038.5 5045.7 5061.4 08:10:36 INFO - PROCESS | 32729 | 08:10:36 INFO - PROCESS | 32729 | [#5] yui.html Cycles:180 Average:14386.78 Median:60505.89 stddev:18449.88 (30.5%) stddev-sans-first:18472.90 08:10:36 INFO - PROCESS | 32729 | Values: 672.3 683.4 724.1 732.4 743.1 11866.3 12896.7 12919.5 12921.2 12951.8 12629.7 12629.9 12657.6 12701.4 12794.3 646.1 661.6 778.6 799.2 804.6 815.0 816.8 817.9 818.2 819.7 861.6 864.3 865.5 865.6 866.2 688.8 710.0 711.1 713.8 862.5 614.5 634.2 637.3 638.2 639.0 13454.1 13753.7 13793.0 13802.4 13810.3 12772.4 12959.4 13018.9 13171.7 13277.9 10513.5 10611.6 10701.0 10780.7 11043.6 4039.5 4081.2 4089.7 4253.3 4259.3 59311.4 60176.7 60835.1 61050.4 61234.0 9389.2 9463.8 9676.0 9680.4 9725.3 17886.3 17999.7 18047.6 18122.2 18196.4 6391.0 6400.8 6454.3 6478.5 6654.8 3389.5 3469.7 3470.4 3518.1 3597.8 2674.4 2710.8 2728.8 2739.9 2745.7 452.5 459.3 461.6 462.6 466.9 551.5 568.3 570.8 574.7 583.3 7596.2 7813.3 8020.8 8094.9 8200.9 566.9 567.9 576.5 576.8 580.5 3953.6 4142.8 4156.8 4192.4 4269.2 64459.4 65543.7 65828.3 66179.0 67368.0 57960.7 58489.0 59010.7 59194.0 59257.0 51096.7 52943.7 53099.7 53795.5 54080.5 7471.5 7709.4 7778.5 7903.4 7927.2 36153.6 36288.6 36717.1 37157.6 37192.3 42027.2 44760.3 44761.8 45037.3 45330.5 9208.5 9293.6 9305.4 9333.8 9446.5 9956.5 9983.3 10012.8 10036.5 10143.9 9179.6 9179.9 9251.9 9413.5 9779.6 9105.3 9165.3 9433.3 9490.3 10289.2 8936.6 8937.7 8941.6 8995.7 8999.9 8385.6 8447.5 8554.5 8659.7 9030.8 9506.3 9527.2 9550.8 9615.9 9658.5 08:10:36 INFO - PROCESS | 32729 | -------- Summary: end -------- 08:10:36 INFO - PROCESS | 32729 | 08:10:37 INFO - TEST-INFO | 32729: exit 0 08:10:37 INFO - TEST-OK | dromaeo_css | took 1120772ms 08:10:37 INFO - TEST-START | kraken 08:10:37 INFO - operating with platform_type : linux_ 08:10:37 INFO - Initialising browser for kraken test... 08:10:37 INFO - TEST-INFO | started process 565 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppzsJgs/profile http://localhost:60921/getInfo.html) 08:10:41 INFO - PROCESS | 565 | __metrics Screen width/height:1600/1200 08:10:41 INFO - PROCESS | 565 | colorDepth:24 08:10:41 INFO - PROCESS | 565 | Browser inner width/height: 1024/697 08:10:41 INFO - PROCESS | 565 | __metrics 08:10:41 INFO - PROCESS | 565 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:10:41 INFO - PROCESS | 565 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:42 INFO - PROCESS | 565 | console.error: PushService: 08:10:42 INFO - PROCESS | 565 | stateChangeProcessEnqueue: Error transitioning state 08:10:42 INFO - PROCESS | 565 | UnknownError 08:10:43 INFO - TEST-INFO | 565: exit 0 08:10:43 INFO - Browser initialized. 08:10:43 INFO - Running cycle 1/1 for kraken test... 08:10:43 INFO - TEST-INFO | started process 651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppzsJgs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:55 INFO - PROCESS | 651 | RSS: Main: 152162304 08:10:55 INFO - PROCESS | 651 | 08:10:57 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60921/tests/kraken/driver.html?testName=audio-beat-detection) 08:10:58 INFO - PROCESS | 651 | RSS: Main: 206209024 08:10:58 INFO - PROCESS | 651 | 08:11:01 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60921/tests/kraken/driver.html?testName=audio-dft) 08:11:02 INFO - PROCESS | 651 | RSS: Main: 186404864 08:11:02 INFO - PROCESS | 651 | 08:11:05 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60921/tests/kraken/driver.html?testName=audio-fft) 08:11:05 INFO - PROCESS | 651 | RSS: Main: 268931072 08:11:05 INFO - PROCESS | 651 | 08:11:08 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60921/tests/kraken/driver.html?testName=audio-oscillator) 08:11:08 INFO - PROCESS | 651 | RSS: Main: 179675136 08:11:08 INFO - PROCESS | 651 | 08:11:10 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60921/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:11:11 INFO - PROCESS | 651 | RSS: Main: 182661120 08:11:11 INFO - PROCESS | 651 | 08:11:14 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60921/tests/kraken/driver.html?testName=imaging-darkroom) 08:11:15 INFO - PROCESS | 651 | RSS: Main: 222101504 08:11:15 INFO - PROCESS | 651 | 08:11:18 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60921/tests/kraken/driver.html?testName=imaging-desaturate) 08:11:19 INFO - PROCESS | 651 | RSS: Main: 216453120 08:11:19 INFO - PROCESS | 651 | 08:11:22 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60921/tests/kraken/driver.html?testName=json-parse-financial) 08:11:22 INFO - PROCESS | 651 | RSS: Main: 217903104 08:11:22 INFO - PROCESS | 651 | 08:11:24 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60921/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:11:25 INFO - PROCESS | 651 | RSS: Main: 181477376 08:11:25 INFO - PROCESS | 651 | 08:11:27 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:11:27 INFO - PROCESS | 651 | RSS: Main: 189251584 08:11:27 INFO - PROCESS | 651 | 08:11:29 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:11:30 INFO - PROCESS | 651 | RSS: Main: 197271552 08:11:30 INFO - PROCESS | 651 | 08:11:33 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:11:34 INFO - PROCESS | 651 | RSS: Main: 201375744 08:11:34 INFO - PROCESS | 651 | 08:11:37 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:11:37 INFO - PROCESS | 651 | RSS: Main: 203288576 08:11:37 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | Cycle 1(1): loaded http://localhost:60921/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60921/tests/kraken/driver.html?testName=ai-astar) 08:11:39 INFO - PROCESS | 651 | RSS: Main: 253857792 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | __start_tp_report 08:11:39 INFO - PROCESS | 651 | _x_x_mozilla_page_load 08:11:39 INFO - PROCESS | 651 | _x_x_mozilla_page_load_details 08:11:39 INFO - PROCESS | 651 | |i|pagename|runs| 08:11:39 INFO - PROCESS | 651 | |0;ai-astar;114;116;115;115;118;115;113;116;114;114 08:11:39 INFO - PROCESS | 651 | |1;audio-beat-detection;153;127;126;165;126;124;127;130;157;126 08:11:39 INFO - PROCESS | 651 | |2;audio-dft;161;162;160;160;161;164;161;165;160;159 08:11:39 INFO - PROCESS | 651 | |3;audio-fft;77;76;76;122;76;76;76;76;98;76 08:11:39 INFO - PROCESS | 651 | |4;audio-oscillator;85;85;111;87;85;84;105;86;84;112 08:11:39 INFO - PROCESS | 651 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;118;118;118 08:11:39 INFO - PROCESS | 651 | |6;imaging-darkroom;149;149;150;148;150;149;150;149;149;149 08:11:39 INFO - PROCESS | 651 | |7;imaging-desaturate;95;95;95;96;96;95;95;95;95;95 08:11:39 INFO - PROCESS | 651 | |8;json-parse-financial;63;60;62;60;62;62;62;62;62;62 08:11:39 INFO - PROCESS | 651 | |9;json-stringify-tinderbox;67;68;67;67;68;67;67;68;67;68 08:11:39 INFO - PROCESS | 651 | |10;stanford-crypto-aes;92;94;85;84;92;87;84;84;83;90 08:11:39 INFO - PROCESS | 651 | |11;stanford-crypto-ccm;136;133;134;143;137;133;132;131;210;150 08:11:39 INFO - PROCESS | 651 | |12;stanford-crypto-pbkdf2;178;180;173;177;177;178;177;174;173;174 08:11:39 INFO - PROCESS | 651 | |13;stanford-crypto-sha256-iterative;69;67;68;72;68;68;68;67;66;65 08:11:39 INFO - PROCESS | 651 | __end_tp_report 08:11:39 INFO - PROCESS | 651 | __start_cc_report 08:11:39 INFO - PROCESS | 651 | _x_x_mozilla_cycle_collect,1813 08:11:39 INFO - PROCESS | 651 | __end_cc_report 08:11:39 INFO - PROCESS | 651 | __startTimestamp1484323899704__endTimestamp 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | ------- Summary: start ------- 08:11:39 INFO - PROCESS | 651 | Number of tests: 14 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:1.41 (1.2%) stddev-sans-first:1.45 08:11:39 INFO - PROCESS | 651 | Values: 114.0 116.0 115.0 115.0 118.0 115.0 113.0 116.0 114.0 114.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:128.50 stddev:15.68 (12.2%) stddev-sans-first:15.39 08:11:39 INFO - PROCESS | 651 | Values: 153.0 127.0 126.0 165.0 126.0 124.0 127.0 130.0 157.0 126.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.89 (1.2%) stddev-sans-first:2.00 08:11:39 INFO - PROCESS | 651 | Values: 161.0 162.0 160.0 160.0 161.0 164.0 161.0 165.0 160.0 159.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#3] audio-fft Cycles:10 Average:82.90 Median:76.00 stddev:15.37 (20.2%) stddev-sans-first:16.15 08:11:39 INFO - PROCESS | 651 | Values: 77.0 76.0 76.0 122.0 76.0 76.0 76.0 76.0 98.0 76.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:85.00 stddev:11.85 (13.9%) stddev-sans-first:12.27 08:11:39 INFO - PROCESS | 651 | Values: 85.0 85.0 111.0 87.0 85.0 84.0 105.0 86.0 84.0 112.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 08:11:39 INFO - PROCESS | 651 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 118.0 118.0 118.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 08:11:39 INFO - PROCESS | 651 | Values: 149.0 149.0 150.0 148.0 150.0 149.0 150.0 149.0 149.0 149.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:11:39 INFO - PROCESS | 651 | Values: 95.0 95.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:62.00 stddev:0.95 (1.5%) stddev-sans-first:0.88 08:11:39 INFO - PROCESS | 651 | Values: 63.0 60.0 62.0 60.0 62.0 62.0 62.0 62.0 62.0 62.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 08:11:39 INFO - PROCESS | 651 | Values: 67.0 68.0 67.0 67.0 68.0 67.0 67.0 68.0 67.0 68.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:88.50 stddev:4.12 (4.7%) stddev-sans-first:4.03 08:11:39 INFO - PROCESS | 651 | Values: 92.0 94.0 85.0 84.0 92.0 87.0 84.0 84.0 83.0 90.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:136.50 stddev:23.95 (17.5%) stddev-sans-first:25.23 08:11:39 INFO - PROCESS | 651 | Values: 136.0 133.0 134.0 143.0 137.0 133.0 132.0 131.0 210.0 150.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:177.00 stddev:2.42 (1.4%) stddev-sans-first:2.47 08:11:39 INFO - PROCESS | 651 | Values: 178.0 180.0 173.0 177.0 177.0 178.0 177.0 174.0 173.0 174.0 08:11:39 INFO - PROCESS | 651 | 08:11:39 INFO - PROCESS | 651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.87 (2.8%) stddev-sans-first:1.94 08:11:39 INFO - PROCESS | 651 | Values: 69.0 67.0 68.0 72.0 68.0 68.0 68.0 67.0 66.0 65.0 08:11:39 INFO - PROCESS | 651 | -------- Summary: end -------- 08:11:39 INFO - PROCESS | 651 | 08:11:40 INFO - TEST-INFO | 651: exit 0 08:11:40 INFO - TEST-OK | kraken | took 63359ms 08:11:40 INFO - SUITE-END | took 1184s 08:11:40 INFO - Completed test suite (00:19:44) 08:11:40 INFO - javascript benchmark 08:11:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2594.961075583866, 2595.9610605840926, 2630.763231309182, 2634.973650263498, 2636.70996190419, 4677.812887484505, 4736.100140973213, 4805.735684537349, 4814.398200224976, 4824.204006338954, 4688.038952014837, 4699.459562150345, 4730.526947305268, 4786.329913812069, 4789.233722604384, 4333.544977777336, 4349.10843277129, 4380.88287486692, 4433.069055498349, 4499.325101234825, 4162.7502349859105, 4396.186705459484, 4467.17357288901, 4484.618807401374, 4540.18276710192, 4350.782460876943, 4384.539623339535, 4464.732116073046, 4472.508024117345, 4533.682642215046, 4332.371806088131, 4375.518692943773, 4388.692791504596, 4413.051193993307, 4455.799489023002, 4370.2133615949115, 4373.518912919576, 4381.364702118175, 4384.7369157850635, 4420.04968931678, 4697.459792123881, 4766.451858036336, 4792.616590672737, 4797.568218860284, 4823.2282834746275, 5055.368078990126, 5058.620603454756, 5100.872478188038, 5112.36095488064, 5179.974100129513, 5144.819931302386, 5154.201098829687, 5155.510226528474, 5164.870878228075, 5166.405863325728, 4727.881802954885, 4812.085703716318, 4815.662903596714, 4817.927731084036, 4859.052484765472, 5058.494150584986, 5105.9489405106215, 5149.897002059938, 5155.587552995751, 5224.52979231871, 4916.287138364917, 4926.33494478248, 4926.482719314492, 4929.3345398371475, 5010.49895010496, 4937.629677774181, 4954.281629163823, 5042.495750424928, 5055.09008378488, 5055.545000949932, 4974.129527332702, 4985.077760614298, 5026.52248036436, 5029.924551131736, 5110.770015349318, 5034.924476132861, 5042.294078828967, 5042.4957504250015, 5042.848714538496, 5092.821751238689, 4279.7218180818145, 4308.0306930940915, 4333.0, 4349.847755328548, 4384.802683879233, 3716.1824398632257, 3743.3636281832155, 3770.4155855842387, 3794.0894185395305, 3873.5545412277384, 4403.779811009436, 4417.2269852775635, 4418.5802348776815, 4423.20382331177, 4455.532169122261, 5116.948830511722, 5120.8719782005, 5122.103631864409, 5133.5379815816, 5183.067047931333, 4337.783110844445, 4377.146456440963, 4418.248897687401, 4462.531434199363, 4464.575865292792, 4642.628589712815, 4659.883502912454, 4662.533746625379, 4694.50707675696, 4699.436067671902, 4885.682430641997, 4887.0, 4890.999999999929, 4894.926576101432, 4940.2342636891335, 4982.825601103944, 5016.423111342242, 5073.264376665436, 5096.617753668415, 5214.113600687968, 4808.3027960946165, 4830.7101573905675, 4841.951580484291, 4853.878653033702, 4893.070316639827, 5055.5955523557295, 5056.974715126548, 5058.241263810458, 5064.594832413324, 5093.898122037464, 5029.899402012013, 5070.062038522885, 5075.593952483866, 5113.6931784091585, 5186.06650802844, 4902.534259245366, 4989.226669866177, 4995.200767877122, 4998.550130488128, 5016.398032236009, 4913.926291105708, 4955.479674634255, 4956.504349565159, 4957.776900039579, 5001.0, 4765.833195838268, 4768.332433459249, 4780.689255198401, 4816.759162041813, 4849.660523763303, 4921.0, 4933.851984440473, 4948.777305021211, 4963.503649635008, 4967.379077615298, 4807.302941073455, 4841.443234028132, 4841.4674385816825, 4843.6367272454, 4843.782029808596], "value": 4678.060216114468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.3891757983797, 567.5487987050296, 578.3406916115626, 578.8639669677625, 627.7583130494758, 39888.202235955265, 42045.57954420455, 42218.577814221884, 45666.77166614162, 45720.0, 41796.791016044954, 42310.576894231046, 42346.0, 42416.151676966445, 42482.36276455856, 7623.390128789683, 7761.379089672841, 7785.688572457123, 7866.41001924855, 8044.477108987897, 7655.119661238945, 10169.186465082776, 10378.065974062332, 10428.947855260712, 10783.352998820057, 10491.895081049206, 10565.313254638424, 10567.26029177958, 10602.363858168495, 10613.0, 10478.947605261963, 10544.15646748266, 10552.314099583502, 10557.366558006468, 10561.207909406749, 10357.844632330522, 10428.322159059637, 10443.738906527322, 10445.582176712922, 10448.582056717722, 38317.808410958045, 38654.0, 38772.22455550901, 38833.0, 38877.41683874744, 3580.1944562473236, 3673.522442082512, 3680.5215322008235, 3712.7029837612927, 3735.3751118263644, 28225.85887070572, 29075.0, 29100.41799164026, 29131.708682913322, 29181.70818291811, 3409.9147521311916, 3428.5542879425516, 3608.4046132388125, 3621.9275614487565, 3625.1299688074673, 227895.0, 228623.0, 230058.0, 230416.84791576103, 231169.0, 52709.7364513175, 53047.20429193565, 53053.204201937, 53096.73451632717, 53163.734181328844, 55504.0, 55565.72217138889, 55648.16527752087, 55675.0, 55725.721371393694, 12621.242725436503, 12926.741465170831, 12929.612111636667, 12932.159409638343, 12940.611781646377, 8617.95691021541, 8892.0, 9461.432314061178, 9659.082387173208, 9667.613295468103, 14841.257937103102, 14985.850141498664, 14991.925040374947, 16332.918335408485, 16689.833101668828, 5553.8333849984565, 6321.494280457552, 6355.491560675135, 6376.3623637635865, 6442.323556026643, 740.3189066059239, 827.6914841945725, 858.9312854971712, 868.1535502884637, 881.9426737262057, 26375.0, 27534.311642208704, 28219.43561128766, 28221.0, 28226.294342641188, 896.1397058823602, 904.4076130134772, 914.1589737441569, 940.4968341937167, 1014.0873214107362, 13748.793768093487, 14661.706765864623, 14725.091319751396, 14900.478483253035, 15821.841781582267, 346214.0, 348302.0, 348758.0, 348815.0, 348964.0, 40146.0, 41091.589084108775, 43527.56472435235, 44558.331625025654, 44726.32910506345, 1230.501646833034, 1263.6967127889425, 1276.3299267884436, 1288.9613311600667, 1291.928943908094, 25368.0, 26182.607260891484, 26700.599491007262, 26833.329166770985, 27053.86473067622, 37705.0, 38065.0, 38068.238635227695, 38084.238315232986, 38091.42862857004, 284245.0, 284458.0, 284812.0, 285585.0, 286602.0, 22874.885625571766, 22967.0, 23230.535389292458, 23326.76673233246, 23362.64956025695, 33158.502622461165, 33380.833095834365, 36667.81666091653, 36763.44854827233, 38119.23761524699, 31141.377172456876, 31380.529292060182, 32238.838805805823, 32665.836670816494, 33282.16794580155, 31254.21864453407, 32205.355892881544, 32284.677153228167, 32553.34893302168, 33494.330113398086, 31499.52750708787, 31502.842485787423, 31660.208494786893, 32232.0, 32263.0, 27256.0, 29672.258193545335, 29886.402271954874, 29970.0, 30046.0, 22533.32400028002, 22715.31854044381, 22877.65683514782, 22969.77030229676, 22994.540109198057], "value": 17264.142251867954, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.1871002504513, 391.9431682406053, 395.1602843956592, 399.9160176362964, 417.6700406678724, 12687.936560317197, 14333.784993225097, 15368.923155384235, 18293.0, 18404.907975460137, 14826.184559849204, 15241.542753717406, 15278.694426111471, 15331.386744530206, 15789.28948197331, 4528.275475923861, 4741.9525804742025, 4742.834000809972, 4747.1692453820615, 4749.287606858981, 3610.0, 3778.8299526521237, 4608.1705293047235, 4665.9766701166445, 6090.056041313604, 6215.011196417126, 6346.46055085316, 6349.0, 6353.205849268841, 6354.015127655198, 5620.4660557247, 5626.662400255997, 5660.943390566083, 5664.150377443396, 6344.3655634436645, 5493.23094766733, 5494.532964698005, 5501.064818980783, 5501.807436739735, 5503.807366742145, 12509.249445033327, 17437.47687569375, 19203.32788352401, 21252.36242912715, 21352.5729485411, 2124.5538436928264, 2213.867167969927, 2220.6446968484966, 2458.164224163775, 2518.597024476075, 9257.0, 9347.579358928864, 9357.625694972194, 9565.808683826353, 9768.609255629766, 1717.43324702849, 1730.954796249098, 1759.8592112630959, 1760.119940029985, 1776.5736223306442, 75914.24085759182, 76471.61764191199, 77531.22468775297, 77776.99999999943, 79766.0, 10593.629222977159, 10662.360258384366, 10702.46487675613, 10843.457827108614, 10911.890881091087, 14907.254637268094, 14932.25338733059, 14936.701265974836, 14951.102933824006, 14972.47596334123, 6756.797296081126, 7006.054182685374, 7049.3655570997835, 7202.1357437106835, 7224.349808517259, 6237.844053898598, 6257.56197066201, 6409.55133140685, 6452.322506136882, 6478.481721462366, 4518.819247230074, 4550.317452382169, 4623.594529524161, 4688.390509233778, 4731.4795372509, 1935.1968932892692, 1974.2596526302636, 2000.1999200319988, 2014.204389266261, 2023.9898800505903, 759.1762937213185, 780.4692808889896, 791.6318911706085, 793.2820797178563, 801.6552882260684, 17431.12844357777, 17881.642367152843, 18072.457826265232, 18085.27658893656, 18462.630747384977, 750.9098908131059, 762.771168649403, 767.244264399566, 773.8735508545526, 795.9562224077731, 9729.513524323755, 9930.304878658424, 10025.649102281384, 10026.799464010825, 10052.69841904744, 487639.0, 491280.0, 492290.0, 495712.0, 497353.0, 80123.59938200389, 85521.57239213889, 86710.0, 87134.56432717796, 90829.54585227164, 931.3573634192386, 966.6761634852403, 972.4602845420859, 973.2992245583168, 973.561897146287, 16066.839331607001, 16266.268017939457, 16672.416465423652, 17066.402675906284, 17253.482395528154, 38018.0, 38794.2241155181, 39286.80356598199, 40715.59284407118, 41534.58465415428, 50007.49992500174, 51516.48483515117, 54481.72759136179, 55055.0, 56277.43722562722, 7580.886286705593, 7636.770896873103, 7794.0, 7813.609319533887, 7894.0, 9607.567659455202, 11661.300321980709, 11788.764224715285, 12274.570390036306, 12762.29807360604, 10843.837342440027, 10960.0, 11258.549658013915, 11270.267432616854, 11278.0, 10225.74435639115, 10285.0, 10319.174466042849, 10416.895831041593, 10642.0, 10578.365298082139, 10611.734706632087, 11147.442627868737, 11351.829722554003, 11535.365554894226, 7579.1284002340435, 7733.613319333898, 7772.417068719982, 7824.1002284735705, 8155.918440815516, 9407.905920940702, 10539.0, 11308.0, 11319.886801132212, 11686.0], "value": 8443.493698329936, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.1299674706559, 1373.375114322983, 1374.6494643865813, 1375.463569208009, 1382.723455308938, 6689.5651782634095, 7022.192447868497, 7022.613756243404, 7029.789106326805, 7032.226455089936, 6266.467350275232, 6452.516061295398, 6857.931420685792, 6904.551204171726, 7022.789316320505, 2175.7606663267065, 2202.2292197730744, 2203.7575866654734, 2206.558688262346, 2207.073029327686, 2164.3182397544724, 2200.8459407841456, 2201.0205458570863, 2201.306588424649, 2201.4936564590075, 1858.7676540432446, 1924.595834874678, 2083.197968782015, 2143.967840482394, 2156.7411910570754, 2152.569486102778, 2158.1691048946113, 2159.168720042779, 2159.88120653364, 2162.0378931375467, 2105.4420578546697, 2122.6922096295953, 2126.5002724359765, 2128.30829980257, 2130.360891732474, 7148.678309476034, 7293.234210407883, 7296.124465064173, 7299.4525410594415, 7299.5620262784405, 4248.745075295461, 4371.803268852909, 4383.079553293812, 4384.714993525411, 4385.232584297767, 2815.9859200704072, 2972.346083861547, 2984.089852594958, 2999.3701322722254, 3032.757379409642, 1019.3170575714215, 1070.5664205996584, 1081.2539347850036, 1085.16442339399, 1173.4308860202796, 9385.76535586609, 9418.905810941871, 9431.434113953184, 9437.339386242966, 9438.150566449052, 3935.7244992850797, 4209.705320627527, 4220.0293932395725, 4282.785860706952, 4287.249731297011, 8253.463524870864, 8258.174182581814, 8262.297704695167, 8264.669413223404, 8267.586620669063, 4390.473143222834, 4394.758288294111, 4396.384506169139, 4397.362382454527, 4399.472063352354, 3609.296187243462, 3720.4605332226674, 3837.5970523094675, 3969.980150099289, 4052.49343832021, 3516.2748869626244, 3530.0821786335223, 3662.487251784752, 3665.9083522911606, 3704.5369328833895, 1185.9407029648482, 1208.3897631695938, 1213.4880796750817, 1215.1615385384234, 1220.1824777399163, 818.1491249100868, 825.7440193540021, 825.9669613215405, 833.7873842170208, 836.030204962253, 4668.066386722601, 4745.3593764842, 4770.189067858472, 4783.210770222944, 4992.051510213048, 538.6014349381508, 539.4982666120467, 543.4836904940322, 546.4071482441595, 556.3240662594967, 3034.969650303513, 3066.9386612268077, 3072.2165847708693, 3082.1370016395445, 3164.4462219112024, 8123.106458289583, 8432.367572432093, 8442.240198382175, 8478.618462169217, 8497.915020849836, 8367.623456944451, 8384.161583841567, 8389.874151887727, 8391.832163356821, 8396.580170991549, 6648.933510664831, 6744.460443164535, 6745.291744366869, 6746.190457145174, 6748.662566871539, 4537.001859591017, 4611.400517932647, 4618.353430519797, 4644.2801365788355, 4672.672912896064, 7642.5032372895585, 7726.575038372944, 7757.573333466714, 7780.3386712130105, 7902.091259505, 7793.766187014398, 7858.253465920729, 7980.600969951363, 8046.959765201136, 8069.878951815845, 6026.487748541423, 6086.695665216633, 6114.0, 6136.355682653257, 6180.0, 6500.5449618526245, 6544.411003009752, 6555.311692272243, 6557.409833115043, 6557.508186885907, 5855.0, 5947.9702601486715, 6123.693815309128, 6145.9692701536205, 6203.751849925952, 5838.562107842014, 5851.327097383855, 5914.231149950651, 5919.437653422918, 5946.13781001761, 5548.722563871871, 5564.415736347625, 5605.523530499953, 5608.214849921096, 5609.775608975595, 4220.788960552021, 4222.767747773779, 4326.178026175016, 4329.393884856059, 4423.469183697976, 4160.563140870286, 4163.458750362433, 4210.957890421179, 4267.16790225903, 4310.655147588122], "value": 3822.4869744135794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.8217326989965, 347.4587146001757, 347.52389226759334, 348.38857804553004, 358.0404515897396, 4698.577128058474, 4862.829800956967, 4918.139325618016, 4931.704097754133, 4946.851594452168, 4697.506761790014, 4824.734639594821, 4855.854324370266, 4862.805487780493, 4863.440704319004, 3880.2821478026513, 3924.6075392460666, 3936.4292177634225, 3942.881713548584, 3962.385830196317, 3696.5009723687226, 3919.745216560929, 3922.7643292362814, 3933.2920074386598, 3956.6636835868985, 3716.3682174030346, 3734.514513113305, 3735.1969326594913, 3765.3598888188935, 3781.470594116826, 3744.4008958566633, 3750.19370835269, 3758.718096142773, 3760.1163726524246, 3776.169242766587, 3293.4730443128983, 3296.5549650797075, 3296.785708928936, 3304.686054824788, 3315.292624298488, 4676.859694209179, 4709.458412282597, 4733.455652599926, 4759.381280433521, 4786.425628924517, 4248.957510424887, 4297.935530967038, 4311.655067594585, 4314.82740690372, 4318.179545886303, 5106.748846532586, 5118.38579370478, 5118.564921981636, 5119.38567371914, 5126.846194614168, 3523.612402635708, 3611.259691763182, 3619.7828130312, 3622.3660859349507, 3623.800690961996, 5279.815206467793, 5292.0, 5313.814016509442, 5350.892982140335, 5359.919601205985, 3619.5475565554307, 3640.5995340512522, 3657.158853463718, 3661.5972243053243, 3665.3585622515952, 3662.468942003448, 3677.2277821657217, 3682.834272457746, 3699.1491956850077, 3708.721845861571, 4180.581941805856, 4264.552222016706, 4264.594863487963, 4288.185244803477, 4305.763183024901, 5129.256257842592, 5140.383154021467, 5141.0232055909255, 5146.356705411824, 5146.713321669583, 1208.7945049341633, 1227.2268470863298, 1229.237872519044, 1244.9813252801216, 1263.6524955637274, 819.942604017725, 833.6998680475024, 834.7120243515977, 843.9831203376021, 853.2022558907317, 4338.717983331074, 4360.19052093384, 4385.342198670161, 4432.847459660511, 4438.71148375361, 5127.102757017507, 5145.0, 5154.329937108227, 5161.406438259573, 5176.663516871391, 4280.357946308079, 4524.411826462539, 4576.244919588231, 4577.450705915245, 4586.128635559233, 2741.7806575473915, 2743.2181828179273, 2744.945101098007, 2762.337039110599, 2769.778417726577, 4766.189747742892, 4829.444613869379, 4842.346283251786, 4903.926441103386, 4919.581835544019, 5291.708956007379, 5299.5230429261555, 5299.920501192485, 5303.04545181871, 5341.385740639798, 4827.879303017312, 4829.855104346875, 4848.80604775805, 4866.878328041827, 4891.926621100686, 4959.20652695567, 5064.8987020258655, 5101.234814777813, 5167.811403377169, 5168.173092305213, 5035.395752509722, 5057.8229761958155, 5066.594672426123, 5076.7207803571155, 5091.949080509147, 4807.61539076866, 4841.540053694894, 4845.103655823684, 4852.708837469763, 4858.246971719389, 3489.2498112905846, 3581.7492775505466, 3582.5848789727393, 3596.964030359663, 3621.0, 5088.974555127201, 5108.208227724708, 5115.4373018967135, 5150.6137039721425, 5152.690838549699, 4964.578010869117, 4987.202047672355, 5001.899962000813, 5007.674501157413, 5019.447860735243, 4746.572808447257, 4988.351514303117, 4993.625478089085, 5004.4495105537635, 5010.49895010496, 4789.784459699252, 4896.92654610174, 4909.214525676018, 4909.828156014522, 4911.459739428588, 5139.229115632685, 5141.742912854267, 5145.974270128775, 5158.561522270649, 5165.217999790067, 5019.222020586815, 5032.672876263031, 5038.47096054909, 5045.672031317952, 5061.392632884077], "value": 3818.864862290221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.3008071605531, 683.371298405467, 724.1238101896705, 732.3591857125016, 743.1449577960954, 11866.347350895698, 12896.677583060427, 12919.54781582646, 12921.160124591896, 12951.805722914152, 12629.747405051894, 12629.936850315758, 12657.62027139185, 12701.364931753422, 12794.296313702742, 646.1470858466826, 661.646019379632, 778.5562229529171, 799.1648727080216, 804.6017221475348, 815.0260438775665, 816.7549735079482, 817.9468334558264, 818.1981657975188, 819.713100414856, 861.5907443964129, 864.3215076165211, 865.523961820999, 865.645085514939, 866.1771317248607, 688.8243497908027, 709.9645017749143, 711.1253158614933, 713.7787285941375, 862.5299211929513, 614.5329549542336, 634.2452481546944, 637.2639601260552, 638.2436812377348, 638.987220255597, 13454.05821592489, 13753.724925501534, 13793.034487585874, 13802.447902083904, 13810.309484525835, 12772.425240864091, 12959.352032398343, 13018.893394061517, 13171.670708232276, 13277.867221327857, 10513.474326283655, 10611.628592999285, 10701.0, 10780.676579702622, 11043.613473528469, 4039.515258169009, 4081.1837632473625, 4089.7137200395982, 4253.319468884994, 4259.318509038539, 59311.40688593145, 60176.69911650414, 60835.08747368793, 61050.389496104915, 61234.0, 9389.201917836925, 9463.810723785624, 9676.032396760267, 9680.419174849532, 9725.319227653996, 17886.28454861791, 17999.73000404995, 18047.63904721898, 18122.184501697455, 18196.363127290482, 6391.0, 6400.839979000469, 6454.290028096905, 6478.481721462272, 6654.800355989231, 3389.457686770105, 3469.7397695172963, 3470.4273794824076, 3518.0677120563073, 3597.8201089945396, 2674.41162944152, 2710.8102432829905, 2728.7953403494816, 2739.917802465929, 2745.7391547802927, 452.50224752771686, 459.2903963376591, 461.55229427455316, 462.60447317543014, 466.8622756286903, 551.5256879083985, 568.3378863623915, 570.805925985167, 574.7126436781609, 583.2534356024296, 7596.2023987481625, 7813.296803287732, 8020.79948001293, 8094.854863461187, 8200.917990820135, 566.8611190258331, 567.9034564124026, 576.4725276372156, 576.7779404929132, 580.4688709830542, 3953.584873588289, 4142.792860357031, 4156.7921603919685, 4192.392103145088, 4269.210196113667, 64459.35540644627, 65543.67228163828, 65828.34171658318, 66179.0, 67368.0, 57960.71019644959, 58489.0, 59010.704946474994, 59194.0, 59257.0, 51096.73678317177, 52943.735281323345, 53099.73450132724, 53795.46204537983, 54080.459195407544, 7471.476996610185, 7709.383249339927, 7778.49439786412, 7903.407244456574, 7927.16764739694, 36153.638463615025, 36288.63711362852, 36717.08207294839, 37157.628423716495, 37192.25615487621, 42027.159456810085, 44760.328595071755, 44761.776191118835, 45037.32444013277, 45330.54669453263, 9208.493532855757, 9293.581788819654, 9305.441673499612, 9333.813323733351, 9446.480443575669, 9956.502174891371, 9983.301168918397, 10012.799744005224, 10036.49817509107, 10143.949280253551, 9179.586918588813, 9179.908200917906, 9251.907480925105, 9413.52932353366, 9779.559919803769, 9105.317101217568, 9165.266778657558, 9433.29250306216, 9490.28822838276, 10289.228307876789, 8936.597853096757, 8937.687180948635, 8941.618537332117, 8995.685151019683, 8999.910000899907, 8385.622646980779, 8447.493150410995, 8554.529500877512, 8659.696910608098, 9030.774230644023, 9506.305912507005, 9527.237820974167, 9550.808983820145, 9615.9038409615, 9658.517074146124], "value": 6016.596768329256, "unit": "score"}], "name": "dromaeo_css", "value": 6255.1264471821, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 115.0, 115.0, 118.0, 115.0, 113.0, 116.0, 114.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 126.0, 165.0, 126.0, 124.0, 127.0, 130.0, 157.0, 126.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 160.0, 161.0, 164.0, 161.0, 165.0, 160.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 122.0, 76.0, 76.0, 76.0, 76.0, 98.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 111.0, 87.0, 85.0, 84.0, 105.0, 86.0, 84.0, 112.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 118.0, 118.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 148.0, 150.0, 149.0, 150.0, 149.0, 149.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 62.0, 60.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 85.0, 84.0, 92.0, 87.0, 84.0, 84.0, 83.0, 90.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 134.0, 143.0, 137.0, 133.0, 132.0, 131.0, 210.0, 150.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 180.0, 173.0, 177.0, 177.0, 178.0, 177.0, 174.0, 173.0, 174.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 72.0, 68.0, 68.0, 68.0, 67.0, 66.0, 65.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1553.6000000000001, "alertThreshold": 2.0}]} 08:11:40 INFO - Return code: 0 08:11:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:11:41 INFO - # TBPL SUCCESS # 08:11:41 INFO - Running post-action listener: _resource_record_post_action 08:11:41 INFO - [mozharness: 2017-01-13 16:11:41.006101Z] Finished run-tests step (success) 08:11:41 INFO - Running post-run listener: _resource_record_post_run 08:11:41 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 12288; Write bytes: 394985472; Read time: 216; Write time: 234980 08:11:41 INFO - TinderboxPrint: CPU usage
13.1% 08:11:41 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 216 08:11:41 INFO - TinderboxPrint: I/O write bytes / time
394,985,472 / 234,980 08:11:41 INFO - TinderboxPrint: CPU idle
8,328.4 (86.9%) 08:11:41 INFO - TinderboxPrint: CPU user
1,215.0 (12.7%) 08:11:41 INFO - TinderboxPrint: Swap in / out
0 / 0 08:11:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 100204544; Read time: 176; Write time: 65260 08:11:41 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283492352; Read time: 36; Write time: 167796 08:11:41 INFO - Running post-run listener: _upload_blobber_files 08:11:41 INFO - Blob upload gear active. 08:11:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:11:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:11:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:11:42 INFO - (blobuploader) - INFO - Open directory for files ... 08:11:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:11:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:11:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:42 INFO - (blobuploader) - INFO - Done attempting. 08:11:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:11:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:11:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:43 INFO - (blobuploader) - INFO - Done attempting. 08:11:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:11:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:11:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:44 INFO - (blobuploader) - INFO - Done attempting. 08:11:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:11:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:11:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:47 INFO - (blobuploader) - INFO - Done attempting. 08:11:47 INFO - (blobuploader) - INFO - Iteration through files over. 08:11:47 INFO - Return code: 0 08:11:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:11:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:11:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8018db7e4bc6f87a136a825657f0965da0a73e60cc014066a8ce2b70c84b422969b2792d658bcf5400f3bff49d68ee6ccacee1a833ff41a14f95a79572a9ad8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/880bc76df331a9262343043985e07ff8f513783edfd1e5b9bb0a9998d045496c829cc08515c601ed12d2d083b30332136fa999c43f63a02f232a701a4f12aa53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce59f53b931d89c6e5834acdd8c239354abbd35cbb272591b9324e1d87a73a4a94d19c95c8defd900acf2b9ee62fba98ace7da4c29af85c07f0bca627eb091cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42c37cef88e5b3de31b6520b1027808ac6a66b31c9c9fd2984da1bba4af5cba4f2c8974a790f5e5ee31bca6c945e8382c402547680b3175cee50345c831c5ad2"} 08:11:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:11:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:11:47 INFO - Contents: 08:11:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8018db7e4bc6f87a136a825657f0965da0a73e60cc014066a8ce2b70c84b422969b2792d658bcf5400f3bff49d68ee6ccacee1a833ff41a14f95a79572a9ad8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/880bc76df331a9262343043985e07ff8f513783edfd1e5b9bb0a9998d045496c829cc08515c601ed12d2d083b30332136fa999c43f63a02f232a701a4f12aa53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce59f53b931d89c6e5834acdd8c239354abbd35cbb272591b9324e1d87a73a4a94d19c95c8defd900acf2b9ee62fba98ace7da4c29af85c07f0bca627eb091cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42c37cef88e5b3de31b6520b1027808ac6a66b31c9c9fd2984da1bba4af5cba4f2c8974a790f5e5ee31bca6c945e8382c402547680b3175cee50345c831c5ad2"} 08:11:47 INFO - Running post-run listener: copy_logs_to_upload_dir 08:11:47 INFO - Copying logs to upload dir... 08:11:47 INFO - mkdir: /builds/slave/test/build/upload/logs 08:11:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.294815 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-01-13 08:11:47.281068) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 08:11:47.284572) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8018db7e4bc6f87a136a825657f0965da0a73e60cc014066a8ce2b70c84b422969b2792d658bcf5400f3bff49d68ee6ccacee1a833ff41a14f95a79572a9ad8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/880bc76df331a9262343043985e07ff8f513783edfd1e5b9bb0a9998d045496c829cc08515c601ed12d2d083b30332136fa999c43f63a02f232a701a4f12aa53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce59f53b931d89c6e5834acdd8c239354abbd35cbb272591b9324e1d87a73a4a94d19c95c8defd900acf2b9ee62fba98ace7da4c29af85c07f0bca627eb091cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42c37cef88e5b3de31b6520b1027808ac6a66b31c9c9fd2984da1bba4af5cba4f2c8974a790f5e5ee31bca6c945e8382c402547680b3175cee50345c831c5ad2"} build_url:https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/VYATu1urTp-IjwRBJmvXZQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c8018db7e4bc6f87a136a825657f0965da0a73e60cc014066a8ce2b70c84b422969b2792d658bcf5400f3bff49d68ee6ccacee1a833ff41a14f95a79572a9ad8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/880bc76df331a9262343043985e07ff8f513783edfd1e5b9bb0a9998d045496c829cc08515c601ed12d2d083b30332136fa999c43f63a02f232a701a4f12aa53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ce59f53b931d89c6e5834acdd8c239354abbd35cbb272591b9324e1d87a73a4a94d19c95c8defd900acf2b9ee62fba98ace7da4c29af85c07f0bca627eb091cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/42c37cef88e5b3de31b6520b1027808ac6a66b31c9c9fd2984da1bba4af5cba4f2c8974a790f5e5ee31bca6c945e8382c402547680b3175cee50345c831c5ad2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 08:11:47.309107) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 08:11:47.309430) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db7e7c15f3ca076d53731445000001ba-1484322659.57407-2054397240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004412 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 08:11:47.395029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 08:11:47.395345) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 08:11:47.395648) ========= ========= Total master_lag: 0.18 =========