builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0088 starttime: 1468993924.17 results: success (0) buildid: 20160719215540 builduid: b3e89890ddcd4dea949a21a3d5ac1894 revision: 77e960d65f6422b5c55d5c33e257066f59b5b8e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.169709) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.170165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.170442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.237926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.238447) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dhFGlieITd/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-19 22:52:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.53M=0.002s 2016-07-19 22:52:04 (7.53 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.179332 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.440856) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.441192) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dhFGlieITd/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020528 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:04.490372) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 22:52:04.490737) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 77e960d65f6422b5c55d5c33e257066f59b5b8e1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 77e960d65f6422b5c55d5c33e257066f59b5b8e1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dhFGlieITd/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-19 22:52:04,564 truncating revision to first 12 chars 2016-07-19 22:52:04,564 Setting DEBUG logging. 2016-07-19 22:52:04,564 attempt 1/10 2016-07-19 22:52:04,564 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/77e960d65f64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 22:52:05,857 unpacking tar archive at: autoland-77e960d65f64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.573418 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 22:52:06.085150) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:06.085476) ========= script_repo_revision: 77e960d65f6422b5c55d5c33e257066f59b5b8e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:06.085951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:06.086244) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:52:06.102821) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-19 22:52:06.103512) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dhFGlieITd/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:52:06 INFO - MultiFileLogger online at 20160719 22:52:06 in /builds/slave/test 22:52:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:52:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:52:06 INFO - {'append_to_log': False, 22:52:06 INFO - 'base_work_dir': '/builds/slave/test', 22:52:06 INFO - 'blob_upload_branch': 'Autoland', 22:52:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:52:06 INFO - 'branch': 'Autoland', 22:52:06 INFO - 'buildbot_json_path': 'buildprops.json', 22:52:06 INFO - 'config_files': ('talos/mac_config.py',), 22:52:06 INFO - 'default_actions': ('clobber', 22:52:06 INFO - 'read-buildbot-config', 22:52:06 INFO - 'download-and-extract', 22:52:06 INFO - 'populate-webroot', 22:52:06 INFO - 'create-virtualenv', 22:52:06 INFO - 'install', 22:52:06 INFO - 'run-tests'), 22:52:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:52:06 INFO - 'download_minidump_stackwalk': True, 22:52:06 INFO - 'download_symbols': 'ondemand', 22:52:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:52:06 INFO - 'tooltool.py': '/tools/tooltool.py', 22:52:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:52:06 INFO - '/tools/misc-python/virtualenv.py')}, 22:52:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:52:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:52:06 INFO - 'installer_path': 'installer.exe', 22:52:06 INFO - 'log_level': 'info', 22:52:06 INFO - 'log_name': 'talos', 22:52:06 INFO - 'log_to_console': True, 22:52:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:52:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:52:06 INFO - 'opt_config_files': (), 22:52:06 INFO - 'pip_index': False, 22:52:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:52:06 INFO - 'cmd': ('bash', 22:52:06 INFO - '-c', 22:52:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:52:06 INFO - 'enabled': True, 22:52:06 INFO - 'halt_on_failure': False, 22:52:06 INFO - 'name': 'check_screen_resolution'},), 22:52:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:52:06 INFO - 'cmd': ('bash', 22:52:06 INFO - '-c', 22:52:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:52:06 INFO - 'enabled': True, 22:52:06 INFO - 'halt_on_failure': False, 22:52:06 INFO - 'name': 'check_screen_resolution'},), 22:52:06 INFO - 'run_cmd_checks_enabled': True, 22:52:06 INFO - 'sps_profile': False, 22:52:06 INFO - 'sps_profile_interval': 0, 22:52:06 INFO - 'suite': 'dromaeojs', 22:52:06 INFO - 'system_bits': '32', 22:52:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:52:06 INFO - 'title': 't-yosemite-r7-0088', 22:52:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:52:06 INFO - 'use_talos_json': True, 22:52:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:52:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:52:06 INFO - 'work_dir': 'build'} 22:52:06 INFO - [mozharness: 2016-07-20 05:52:06.288751Z] Running clobber step. 22:52:06 INFO - Running pre-action listener: _resource_record_pre_action 22:52:06 INFO - Running main action method: clobber 22:52:06 INFO - rmtree: /builds/slave/test/build 22:52:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:52:07 INFO - Running post-action listener: _resource_record_post_action 22:52:07 INFO - [mozharness: 2016-07-20 05:52:07.746390Z] Finished clobber step (success) 22:52:07 INFO - [mozharness: 2016-07-20 05:52:07.746554Z] Running read-buildbot-config step. 22:52:07 INFO - Running pre-action listener: _resource_record_pre_action 22:52:07 INFO - Running main action method: read_buildbot_config 22:52:07 INFO - Using buildbot properties: 22:52:07 INFO - { 22:52:07 INFO - "project": "", 22:52:07 INFO - "product": "firefox", 22:52:07 INFO - "script_repo_revision": "production", 22:52:07 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:52:07 INFO - "repository": "", 22:52:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:52:07 INFO - "buildid": "20160719215540", 22:52:07 INFO - "pgo_build": "False", 22:52:07 INFO - "basedir": "/builds/slave/test", 22:52:07 INFO - "buildnumber": 56, 22:52:07 INFO - "slavename": "t-yosemite-r7-0088", 22:52:07 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:52:07 INFO - "platform": "yosemite_r7", 22:52:07 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:52:07 INFO - "branch": "autoland", 22:52:07 INFO - "repo_path": "integration/autoland", 22:52:07 INFO - "revision": "77e960d65f6422b5c55d5c33e257066f59b5b8e1", 22:52:07 INFO - "stage_platform": "macosx64", 22:52:07 INFO - "builduid": "b3e89890ddcd4dea949a21a3d5ac1894", 22:52:07 INFO - "slavebuilddir": "test" 22:52:07 INFO - } 22:52:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 22:52:07 INFO - Running post-action listener: _resource_record_post_action 22:52:07 INFO - [mozharness: 2016-07-20 05:52:07.749942Z] Finished read-buildbot-config step (success) 22:52:07 INFO - [mozharness: 2016-07-20 05:52:07.750057Z] Running download-and-extract step. 22:52:07 INFO - Running pre-action listener: _resource_record_pre_action 22:52:07 INFO - Running main action method: download_and_extract 22:52:07 INFO - mkdir: /builds/slave/test/build/tests 22:52:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:07 INFO - https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:52:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:52:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:52:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:52:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 22:52:08 INFO - Downloaded 1199 bytes. 22:52:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:52:08 INFO - Using the following test package requirements: 22:52:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:52:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 22:52:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 22:52:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'jsshell-mac.zip'], 22:52:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 22:52:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:52:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 22:52:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 22:52:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 22:52:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:52:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 22:52:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:52:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:08 INFO - https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:52:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:52:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:52:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:52:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:52:10 INFO - Downloaded 18835954 bytes. 22:52:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:52:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:52:14 INFO - Return code: 0 22:52:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:52:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:14 INFO - https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:52:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:52:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:52:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:52:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:52:15 INFO - Downloaded 18835954 bytes. 22:52:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:52:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:52:18 INFO - Return code: 0 22:52:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:18 INFO - https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:52:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:52:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:52:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:52:20 INFO - Downloaded 14403317 bytes. 22:52:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:52:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:52:20 INFO - Return code: 0 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:20 INFO - https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:52:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:52:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:52:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 22:52:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 22:52:23 INFO - Downloaded 112918996 bytes. 22:52:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:52:23 INFO - mkdir: /builds/slave/test/properties 22:52:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:52:23 INFO - Writing to file /builds/slave/test/properties/build_url 22:52:23 INFO - Contents: 22:52:23 INFO - build_url:https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:52:24 INFO - Running post-action listener: _resource_record_post_action 22:52:24 INFO - Running post-action listener: set_extra_try_arguments 22:52:24 INFO - [mozharness: 2016-07-20 05:52:24.499359Z] Finished download-and-extract step (success) 22:52:24 INFO - [mozharness: 2016-07-20 05:52:24.499481Z] Running populate-webroot step. 22:52:24 INFO - Running pre-action listener: _resource_record_pre_action 22:52:24 INFO - Running main action method: populate_webroot 22:52:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:52:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:52:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:52:24 INFO - '--tppagecycles', 22:52:24 INFO - '7'], 22:52:24 INFO - 'tests': ['tsvgm']}}, 22:52:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:52:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:52:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:52:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:52:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['damp', 'tps']}, 22:52:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'tests': ['damp', 'tps']}, 22:52:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['dromaeo_dom']}, 22:52:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:52:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['basic_compositor_video']}, 22:52:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:52:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['a11yr', 22:52:24 INFO - 'ts_paint', 22:52:24 INFO - 'tpaint', 22:52:24 INFO - 'sessionrestore', 22:52:24 INFO - 'sessionrestore_no_auto_restore', 22:52:24 INFO - 'tabpaint']}, 22:52:24 INFO - 'other-e10s': {'tests': ['a11yr', 22:52:24 INFO - 'ts_paint', 22:52:24 INFO - 'tpaint', 22:52:24 INFO - 'sessionrestore', 22:52:24 INFO - 'sessionrestore_no_auto_restore', 22:52:24 INFO - 'tabpaint']}, 22:52:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:52:24 INFO - 'ts_paint', 22:52:24 INFO - 'tpaint', 22:52:24 INFO - 'sessionrestore', 22:52:24 INFO - 'sessionrestore_no_auto_restore', 22:52:24 INFO - 'tabpaint']}, 22:52:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:52:24 INFO - 'ts_paint', 22:52:24 INFO - 'tpaint', 22:52:24 INFO - 'sessionrestore', 22:52:24 INFO - 'sessionrestore_no_auto_restore', 22:52:24 INFO - 'tabpaint']}, 22:52:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['a11yr', 22:52:24 INFO - 'ts_paint', 22:52:24 INFO - 'tpaint', 22:52:24 INFO - 'sessionrestore', 22:52:24 INFO - 'sessionrestore_no_auto_restore', 22:52:24 INFO - 'tabpaint']}, 22:52:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['a11yr', 22:52:24 INFO - 'ts_paint', 22:52:24 INFO - 'tpaint', 22:52:24 INFO - 'sessionrestore', 22:52:24 INFO - 'sessionrestore_no_auto_restore', 22:52:24 INFO - 'tabpaint']}, 22:52:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['tsvgx', 22:52:24 INFO - 'tsvgr_opacity', 22:52:24 INFO - 'tart', 22:52:24 INFO - 'tscrollx', 22:52:24 INFO - 'cart']}, 22:52:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:52:24 INFO - 'tsvgr_opacity', 22:52:24 INFO - 'tart', 22:52:24 INFO - 'tscrollx', 22:52:24 INFO - 'cart']}, 22:52:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'talos_options': ['--disable-e10s'], 22:52:24 INFO - 'tests': ['tp5o']}, 22:52:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'tests': ['tp5o']}, 22:52:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'talos_options': ['--disable-e10s', 22:52:24 INFO - '--xperf_path', 22:52:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:24 INFO - 'tests': ['tp5n']}, 22:52:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:24 INFO - 'talos_options': ['--xperf_path', 22:52:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:24 INFO - 'tests': ['tp5n']}}, 22:52:24 INFO - 'talos.zip': {'path': '', 22:52:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:52:24 INFO - Running post-action listener: _resource_record_post_action 22:52:24 INFO - [mozharness: 2016-07-20 05:52:24.518640Z] Finished populate-webroot step (success) 22:52:24 INFO - [mozharness: 2016-07-20 05:52:24.518749Z] Running create-virtualenv step. 22:52:24 INFO - Running pre-action listener: _resource_record_pre_action 22:52:24 INFO - Running main action method: create_virtualenv 22:52:24 INFO - Creating virtualenv /builds/slave/test/build/venv 22:52:24 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 22:52:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:52:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:52:24 INFO - Using real prefix '/tools/python27' 22:52:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:52:25 INFO - Installing distribute.............................................................................................................................................................................................done. 22:52:28 INFO - Installing pip.................done. 22:52:28 INFO - Return code: 0 22:52:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52: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', 'pip>=1.5'] in /builds/slave/test/build 22:52: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 pip>=1.5 22:52:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:28 INFO - 'HOME': '/Users/cltbld', 22:52:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:28 INFO - 'LOGNAME': 'cltbld', 22:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:28 INFO - 'MOZ_NO_REMOTE': '1', 22:52:28 INFO - 'NO_EM_RESTART': '1', 22:52:28 INFO - 'PAGER': '/bin/cat', 22:52:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:28 INFO - 'PWD': '/builds/slave/test', 22:52:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:28 INFO - 'SHELL': '/bin/bash', 22:52:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:28 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:28 INFO - 'USER': 'cltbld', 22:52:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:28 INFO - 'XPC_FLAGS': '0x0', 22:52:28 INFO - 'XPC_SERVICE_NAME': '0', 22:52:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:28 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 22:52:28 INFO - Cleaning up... 22:52:28 INFO - Return code: 0 22:52:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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', '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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:52: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 psutil>=3.1.1 22:52:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:28 INFO - 'HOME': '/Users/cltbld', 22:52:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:28 INFO - 'LOGNAME': 'cltbld', 22:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:28 INFO - 'MOZ_NO_REMOTE': '1', 22:52:28 INFO - 'NO_EM_RESTART': '1', 22:52:28 INFO - 'PAGER': '/bin/cat', 22:52:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:28 INFO - 'PWD': '/builds/slave/test', 22:52:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:28 INFO - 'SHELL': '/bin/bash', 22:52:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:28 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:28 INFO - 'USER': 'cltbld', 22:52:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:28 INFO - 'XPC_FLAGS': '0x0', 22:52:28 INFO - 'XPC_SERVICE_NAME': '0', 22:52:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:28 INFO - Downloading/unpacking psutil>=3.1.1 22:52: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 22:52: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 22:52: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 22:52: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 22:52: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 22:52: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 22:52:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:52:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:52:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:52:31 INFO - Installing collected packages: psutil 22:52:31 INFO - Running setup.py install for psutil 22:52:31 INFO - building 'psutil._psutil_osx' extension 22:52:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:52:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:52:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:52:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:52:32 INFO - building 'psutil._psutil_posix' extension 22:52:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:52:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:52:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:52:32 INFO - ^ 22:52:32 INFO - 1 warning generated. 22:52:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:52:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:52:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:52:32 INFO - Successfully installed psutil 22:52:32 INFO - Cleaning up... 22:52:32 INFO - Return code: 0 22:52:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:32 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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52:32 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 22:52:32 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 22:52:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:32 INFO - 'HOME': '/Users/cltbld', 22:52:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:32 INFO - 'LOGNAME': 'cltbld', 22:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:32 INFO - 'MOZ_NO_REMOTE': '1', 22:52:32 INFO - 'NO_EM_RESTART': '1', 22:52:32 INFO - 'PAGER': '/bin/cat', 22:52:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:32 INFO - 'PWD': '/builds/slave/test', 22:52:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:32 INFO - 'SHELL': '/bin/bash', 22:52:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:32 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:32 INFO - 'USER': 'cltbld', 22:52:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:32 INFO - 'XPC_FLAGS': '0x0', 22:52:32 INFO - 'XPC_SERVICE_NAME': '0', 22:52:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:52:32 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 22:52:32 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 22:52:32 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 22:52:32 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 22:52:32 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 22:52:32 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 22:52:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:52:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:52:35 INFO - Installing collected packages: mozsystemmonitor 22:52:35 INFO - Running setup.py install for mozsystemmonitor 22:52:35 INFO - Successfully installed mozsystemmonitor 22:52:35 INFO - Cleaning up... 22:52:35 INFO - Return code: 0 22:52:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:35 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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52:35 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 22:52:35 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 22:52:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:35 INFO - 'HOME': '/Users/cltbld', 22:52:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:35 INFO - 'LOGNAME': 'cltbld', 22:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:35 INFO - 'MOZ_NO_REMOTE': '1', 22:52:35 INFO - 'NO_EM_RESTART': '1', 22:52:35 INFO - 'PAGER': '/bin/cat', 22:52:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:35 INFO - 'PWD': '/builds/slave/test', 22:52:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:35 INFO - 'SHELL': '/bin/bash', 22:52:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:35 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:35 INFO - 'USER': 'cltbld', 22:52:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:35 INFO - 'XPC_FLAGS': '0x0', 22:52:35 INFO - 'XPC_SERVICE_NAME': '0', 22:52:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:35 INFO - Downloading/unpacking jsonschema==2.5.1 22:52:35 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 22:52:35 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 22:52:35 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 22:52:35 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 22:52:35 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 22:52:35 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 22:52:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:52:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:52:38 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 22:52:38 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 22:52:38 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 22:52:38 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 22:52:38 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 22:52:38 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 22:52:38 INFO - Downloading functools32-3.2.3-2.tar.gz 22:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:52:38 INFO - warning: no files found matching '*.txt' 22:52:38 INFO - no previously-included directories found matching 'build' 22:52:38 INFO - no previously-included directories found matching 'dist' 22:52:38 INFO - no previously-included directories found matching '.git*' 22:52:38 INFO - Installing collected packages: jsonschema, functools32 22:52:38 INFO - Running setup.py install for functools32 22:52:39 INFO - warning: no files found matching '*.txt' 22:52:39 INFO - no previously-included directories found matching 'build' 22:52:39 INFO - no previously-included directories found matching 'dist' 22:52:39 INFO - no previously-included directories found matching '.git*' 22:52:39 INFO - Successfully installed jsonschema functools32 22:52:39 INFO - Cleaning up... 22:52:39 INFO - Return code: 0 22:52:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52: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', '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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:52: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 blobuploader==1.2.4 22:52:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:39 INFO - 'HOME': '/Users/cltbld', 22:52:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:39 INFO - 'LOGNAME': 'cltbld', 22:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:39 INFO - 'MOZ_NO_REMOTE': '1', 22:52:39 INFO - 'NO_EM_RESTART': '1', 22:52:39 INFO - 'PAGER': '/bin/cat', 22:52:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:39 INFO - 'PWD': '/builds/slave/test', 22:52:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:39 INFO - 'SHELL': '/bin/bash', 22:52:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:39 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:39 INFO - 'USER': 'cltbld', 22:52:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:39 INFO - 'XPC_FLAGS': '0x0', 22:52:39 INFO - 'XPC_SERVICE_NAME': '0', 22:52:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:39 INFO - Downloading/unpacking blobuploader==1.2.4 22:52:39 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 22:52:39 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 22:52:39 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 22:52:39 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 22:52:39 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 22:52:39 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 22:52:41 INFO - Downloading blobuploader-1.2.4.tar.gz 22:52:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:52:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:52:41 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 22:52:41 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 22:52:41 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 22:52:41 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 22:52:41 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 22:52:41 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 22:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:52:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:52:42 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 22:52:42 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 22:52:42 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 22:52:42 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 22:52:42 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 22:52:42 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 22:52:43 INFO - Downloading docopt-0.6.1.tar.gz 22:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:52:43 INFO - Installing collected packages: blobuploader, requests, docopt 22:52:43 INFO - Running setup.py install for blobuploader 22:52:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:52:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:52:43 INFO - Running setup.py install for requests 22:52:43 INFO - Running setup.py install for docopt 22:52:43 INFO - Successfully installed blobuploader requests docopt 22:52:43 INFO - Cleaning up... 22:52:44 INFO - Return code: 0 22:52:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:52:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:44 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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52:44 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 22:52:44 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 22:52:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:44 INFO - 'HOME': '/Users/cltbld', 22:52:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:44 INFO - 'LOGNAME': 'cltbld', 22:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:44 INFO - 'MOZ_NO_REMOTE': '1', 22:52:44 INFO - 'NO_EM_RESTART': '1', 22:52:44 INFO - 'PAGER': '/bin/cat', 22:52:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:44 INFO - 'PWD': '/builds/slave/test', 22:52:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:44 INFO - 'SHELL': '/bin/bash', 22:52:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:44 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:44 INFO - 'USER': 'cltbld', 22:52:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:44 INFO - 'XPC_FLAGS': '0x0', 22:52:44 INFO - 'XPC_SERVICE_NAME': '0', 22:52:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3fHfsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-IwJg3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-2aLs0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-v6wS2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Tk4ed_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Kb3sHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:52:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:52:44 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-0QDco1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-xF9etH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-F2K_D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-sRh6gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-QTC7BM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-dxjg5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-yw6PuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-_pKT9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-XoKAx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-MuaWxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:52:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:52:45 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-sNQZU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:52:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:52:46 INFO - Running setup.py install for manifestparser 22:52:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:52:46 INFO - Running setup.py install for mozcrash 22:52:46 INFO - Running setup.py install for mozdebug 22:52:46 INFO - Running setup.py install for mozdevice 22:52:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:52:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:52:46 INFO - Running setup.py install for mozfile 22:52:46 INFO - Running setup.py install for mozhttpd 22:52:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:52:46 INFO - Running setup.py install for mozinfo 22:52:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Running setup.py install for mozInstall 22:52:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Running setup.py install for mozleak 22:52:47 INFO - Running setup.py install for mozlog 22:52:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Running setup.py install for moznetwork 22:52:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:52:47 INFO - Running setup.py install for mozprocess 22:52:47 INFO - Running setup.py install for mozprofile 22:52:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:52:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:52:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:52:48 INFO - Running setup.py install for mozrunner 22:52:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:52:48 INFO - Running setup.py install for mozscreenshot 22:52:48 INFO - Running setup.py install for moztest 22:52:48 INFO - Running setup.py install for mozversion 22:52:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:52:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:52:48 INFO - Cleaning up... 22:52:48 INFO - Return code: 0 22:52:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:52:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:48 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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52:48 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 22:52:48 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 22:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:48 INFO - 'HOME': '/Users/cltbld', 22:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:48 INFO - 'LOGNAME': 'cltbld', 22:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:48 INFO - 'MOZ_NO_REMOTE': '1', 22:52:48 INFO - 'NO_EM_RESTART': '1', 22:52:48 INFO - 'PAGER': '/bin/cat', 22:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:48 INFO - 'PWD': '/builds/slave/test', 22:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:48 INFO - 'SHELL': '/bin/bash', 22:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:48 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:48 INFO - 'USER': 'cltbld', 22:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:48 INFO - 'XPC_FLAGS': '0x0', 22:52:48 INFO - 'XPC_SERVICE_NAME': '0', 22:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:52:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-zFdQ1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-lco5PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-JAUqW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-1RxJdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-mKeCIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-e2V6Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Ih3v3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hhRKM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-odD_5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:52:49 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)) 22:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:52:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-37gfbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-xrwhhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:52:50 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-wSNF2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-6bTdC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:52:50 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-MhBMiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-WW2EWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:52:50 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-YyEvHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 22:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:52:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-g1qzco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:52:50 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)) 22:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:52:50 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)) 22:52:50 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:52:50 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:52:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:52:50 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 22:52:50 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 22:52:50 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 22:52:50 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 22:52:50 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 22:52:50 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 22:52:53 INFO - Downloading blessings-1.6.tar.gz 22:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:52:53 INFO - Installing collected packages: blessings 22:52:53 INFO - Running setup.py install for blessings 22:52:53 INFO - Successfully installed blessings 22:52:53 INFO - Cleaning up... 22:52:53 INFO - Return code: 0 22:52:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:52:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:52:53 INFO - Reading from file tmpfile_stdout 22:52:53 INFO - Current package versions: 22:52:53 INFO - blessings == 1.6 22:52:53 INFO - blobuploader == 1.2.4 22:52:53 INFO - docopt == 0.6.1 22:52:53 INFO - functools32 == 3.2.3-2 22:52:53 INFO - jsonschema == 2.5.1 22:52:53 INFO - manifestparser == 1.1 22:52:53 INFO - mozInstall == 1.12 22:52:53 INFO - mozcrash == 0.17 22:52:53 INFO - mozdebug == 0.1 22:52:53 INFO - mozdevice == 0.48 22:52:53 INFO - mozfile == 1.2 22:52:53 INFO - mozhttpd == 0.7 22:52:53 INFO - mozinfo == 0.9 22:52:53 INFO - mozleak == 0.1 22:52:53 INFO - mozlog == 3.3 22:52:53 INFO - moznetwork == 0.27 22:52:53 INFO - mozprocess == 0.23 22:52:53 INFO - mozprofile == 0.28 22:52:53 INFO - mozrunner == 6.12 22:52:53 INFO - mozscreenshot == 0.1 22:52:53 INFO - mozsystemmonitor == 0.3 22:52:53 INFO - moztest == 0.7 22:52:53 INFO - mozversion == 1.4 22:52:53 INFO - psutil == 3.1.1 22:52:53 INFO - requests == 1.2.3 22:52:53 INFO - wsgiref == 0.1.2 22:52:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:52:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:53 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:53 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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52:53 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 22:52:53 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 22:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:53 INFO - 'HOME': '/Users/cltbld', 22:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:53 INFO - 'LOGNAME': 'cltbld', 22:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:53 INFO - 'MOZ_NO_REMOTE': '1', 22:52:53 INFO - 'NO_EM_RESTART': '1', 22:52:53 INFO - 'PAGER': '/bin/cat', 22:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:53 INFO - 'PWD': '/builds/slave/test', 22:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:53 INFO - 'SHELL': '/bin/bash', 22:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:53 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:53 INFO - 'USER': 'cltbld', 22:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:53 INFO - 'XPC_FLAGS': '0x0', 22:52:53 INFO - 'XPC_SERVICE_NAME': '0', 22:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 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)) 22:52:54 INFO - Cleaning up... 22:52:54 INFO - Return code: 0 22:52:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:52:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:54 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 0x10203ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd533ca27f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:52:54 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 22:52:54 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 22:52:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:52:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:54 INFO - 'HOME': '/Users/cltbld', 22:52:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:54 INFO - 'LOGNAME': 'cltbld', 22:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:54 INFO - 'MOZ_NO_REMOTE': '1', 22:52:54 INFO - 'NO_EM_RESTART': '1', 22:52:54 INFO - 'PAGER': '/bin/cat', 22:52:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:54 INFO - 'PWD': '/builds/slave/test', 22:52:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:54 INFO - 'SHELL': '/bin/bash', 22:52:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:52:54 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:52:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:54 INFO - 'USER': 'cltbld', 22:52:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:54 INFO - 'XPC_FLAGS': '0x0', 22:52:54 INFO - 'XPC_SERVICE_NAME': '0', 22:52:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:52:54 INFO - Cleaning up... 22:52:54 INFO - Return code: 0 22:52:54 INFO - Running post-action listener: _resource_record_post_action 22:52:54 INFO - Running post-action listener: _start_resource_monitoring 22:52:54 INFO - Starting resource monitoring. 22:52:54 INFO - [mozharness: 2016-07-20 05:52:54.424615Z] Finished create-virtualenv step (success) 22:52:54 INFO - [mozharness: 2016-07-20 05:52:54.425622Z] Running install step. 22:52:54 INFO - Running pre-action listener: _resource_record_pre_action 22:52:54 INFO - Running main action method: install 22:52:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:52:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:52:54 INFO - Reading from file tmpfile_stdout 22:52:54 INFO - Detecting whether we're running mozinstall >=1.0... 22:52:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:52:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:52:54 INFO - Reading from file tmpfile_stdout 22:52:54 INFO - Output received: 22:52:54 INFO - Usage: mozinstall [options] installer 22:52:54 INFO - Options: 22:52:54 INFO - -h, --help show this help message and exit 22:52:54 INFO - -d DEST, --destination=DEST 22:52:54 INFO - Directory to install application into. [default: 22:52:54 INFO - "/builds/slave/test"] 22:52:54 INFO - --app=APP Application being installed. [default: firefox] 22:52:54 INFO - mkdir: /builds/slave/test/build/application 22:52:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:52:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:53:29 INFO - Reading from file tmpfile_stdout 22:53:29 INFO - Output received: 22:53:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:53:29 INFO - Running post-action listener: _resource_record_post_action 22:53:29 INFO - [mozharness: 2016-07-20 05:53:29.349266Z] Finished install step (success) 22:53:29 INFO - [mozharness: 2016-07-20 05:53:29.349398Z] Running run-tests step. 22:53:29 INFO - Running pre-action listener: _resource_record_pre_action 22:53:29 INFO - Running main action method: run_tests 22:53:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:53:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:53:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:53:29 INFO - 2016-07-19 22:53:29.363 screenresolution[1906:15904] starting screenresolution argv=screenresolution get 22:53:29 INFO - 2016-07-19 22:53:29.366 screenresolution[1906:15904] Display 0: 1600x1200x32@60 22:53:29 INFO - 2016-07-19 22:53:29.372 screenresolution[1907:15906] starting screenresolution argv=screenresolution list 22:53:29 INFO - Available Modes on Display 0 22:53:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:53:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:53:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:53:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:53:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:53:29 INFO - Intel Iris: 22:53:29 INFO - Chipset Model: Intel Iris 22:53:29 INFO - Type: GPU 22:53:29 INFO - Bus: Built-In 22:53:29 INFO - VRAM (Dynamic, Max): 1536 MB 22:53:29 INFO - Vendor: Intel (0x8086) 22:53:29 INFO - Device ID: 0x0a2e 22:53:29 INFO - Revision ID: 0x0009 22:53:29 INFO - Displays: 22:53:29 INFO - Display: 22:53:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:53:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:53:29 INFO - Display Serial Number: 1600x1200 60 22:53:29 INFO - Main Display: Yes 22:53:29 INFO - Mirror: Off 22:53:29 INFO - Online: Yes 22:53:29 INFO - Rotation: Supported 22:53:29 INFO - Return code: 0 22:53:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:53:29 INFO - Python 2.7.3 22:53:29 INFO - Return code: 0 22:53:29 INFO - grabbing minidump binary from tooltool 22:53:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:29 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/macosx64/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 0x101fa0d30>, '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 0x101d05db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:53:29 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:53:29 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:53:29 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:53:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:53:29 INFO - Return code: 0 22:53:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:53:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:53:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:53:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:53:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:53:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 22:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --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 22:53:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dhFGlieITd/Render', 22:53:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:29 INFO - 'HOME': '/Users/cltbld', 22:53:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:29 INFO - 'LOGNAME': 'cltbld', 22:53:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:53:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:53:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:29 INFO - 'MOZ_NO_REMOTE': '1', 22:53:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:53:29 INFO - 'NO_EM_RESTART': '1', 22:53:29 INFO - 'PAGER': '/bin/cat', 22:53:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:29 INFO - 'PWD': '/builds/slave/test', 22:53:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:53:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:29 INFO - 'SHELL': '/bin/bash', 22:53:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners', 22:53:29 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 22:53:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:29 INFO - 'USER': 'cltbld', 22:53:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:29 INFO - 'XPC_FLAGS': '0x0', 22:53:29 INFO - 'XPC_SERVICE_NAME': '0', 22:53:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--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 22:53:29 INFO - mozversion application_buildid: 20160719215540 22:53:29 INFO - mozversion application_changeset: 77e960d65f6422b5c55d5c33e257066f59b5b8e1 22:53:29 INFO - mozversion application_display_name: Nightly 22:53:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:53:29 INFO - mozversion application_name: Firefox 22:53:29 INFO - mozversion application_remotingname: firefox 22:53:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:53:29 INFO - mozversion application_vendor: Mozilla 22:53:29 INFO - mozversion application_version: 50.0a1 22:53:29 INFO - mozversion platform_buildid: 20160719215540 22:53:29 INFO - mozversion platform_changeset: 77e960d65f6422b5c55d5c33e257066f59b5b8e1 22:53:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:53:29 INFO - mozversion platform_version: 50.0a1 22:53:29 INFO - using testdate: 1468994009 22:53:29 INFO - actual date: 1468994009 22:53:29 INFO - starting webserver on 'localhost:54499' 22:53:29 INFO - SUITE-START | Running 2 tests 22:53:29 INFO - TEST-START | dromaeo_css 22:53:29 INFO - operating with platform_type : mac_ 22:53:29 INFO - Initialising browser for dromaeo_css test... 22:53:29 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpsRIYZ0/profile http://localhost:54499/getInfo.html) 22:53:32 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 22:53:32 INFO - PROCESS | 1917 | colorDepth:24 22:53:32 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 22:53:32 INFO - PROCESS | 1917 | __metrics 22:53:32 INFO - PROCESS | 1917 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:53:32 INFO - PROCESS | 1917 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:53:33 INFO - TEST-INFO | 1917: exit 0 22:53:33 INFO - Browser initialized. 22:53:33 INFO - Running cycle 1/1 for dromaeo_css test... 22:53:33 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpsRIYZ0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:53:44 INFO - PROCESS | 1919 | RSS: Main: 245039104 22:53:44 INFO - PROCESS | 1919 | 22:54:36 INFO - PROCESS | 1919 | 1468994076408 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:56:47 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:54499/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54499/tests/dromaeo/cssquery-ext.html) 22:56:48 INFO - PROCESS | 1919 | RSS: Main: 336494592 22:56:48 INFO - PROCESS | 1919 | 22:59:51 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:54499/tests/dromaeo/cssquery-ext.html (next: http://localhost:54499/tests/dromaeo/cssquery-jquery.html) 22:59:52 INFO - PROCESS | 1919 | RSS: Main: 381681664 22:59:52 INFO - PROCESS | 1919 | 23:02:54 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:54499/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54499/tests/dromaeo/cssquery-mootools.html) 23:02:55 INFO - PROCESS | 1919 | RSS: Main: 371683328 23:02:55 INFO - PROCESS | 1919 | 23:05:57 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:54499/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54499/tests/dromaeo/cssquery-prototype.html) 23:05:58 INFO - PROCESS | 1919 | RSS: Main: 366473216 23:05:58 INFO - PROCESS | 1919 | 23:09:01 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:54499/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54499/tests/dromaeo/cssquery-yui.html) 23:09:02 INFO - PROCESS | 1919 | RSS: Main: 345665536 23:09:02 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:54499/tests/dromaeo/cssquery-yui.html (next: http://localhost:54499/tests/dromaeo/cssquery-dojo.html) 23:12:05 INFO - PROCESS | 1919 | RSS: Main: 419840000 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | __start_tp_report 23:12:05 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 23:12:05 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 23:12:05 INFO - PROCESS | 1919 | |i|pagename|runs| 23:12:05 INFO - PROCESS | 1919 | |0;dojo.html;2769.6676398832137;2833.631627888374;3333.100062982993;3361.0421030006455;3365.5961284645846;5610.24261724667;5634.3802181759975;5701.372848986608;5742.138679198122;5778.566607504438;5704.942950570493;5739.196512488255;5743.885122297552;5750.166225897243;5754.1944127822135;5174.818881339144;5212.765425555859;5214.556762675177;5255.237990491377;5294.947050529503;5275.182346736243;5285.867853303679;5293.04725149473;5295.549878260353;5299.390570084451;5179.093658609747;5202;5211.088059589576;5211.296474975868;5293.3647962244395;5275.762590683428;5290.39160496544;5299.231611416361;5312.946870531284;5329.067413202674;5087.16061849798;5092.949070509285;5105.719185444799;5221.425643179247;5231.058409486291;5639.4642508962;5653.010723123437;5690.7439165237665;5701.6864072476;5709.371969083398;6237.189165408513;6253.937460625381;6256.217972753406;6268.15379923709;6281.309056003836;6055.222517765706;6222.595531290497;6295.905561416583;6298.779542715983;6312.021636646281;5829.446202610745;5858.677772722456;5862.677552734641;5888.676122813201;5891.911621325684;6263.279722831932;6281.403266689657;6282.340354262828;6285.025820997753;6301.432871041537;5983.461488466059;6092.573519853657;6097.050200442776;6143.692815359214;6174.8147555573405;6283.057541368831;6283.434490895841;6286.811395658137;6294.339094395114;6310.1796766420075;6183.258009038944;6259.6870156492;6267.4986001119805;6279;6282.685865706696;6086.1175129606845;6107.006761825774;6144.972159187547;6194.752209911643;6223.782167624111;4853.708777473363;4903.092927808367;4916.483769204254;4917.1149193144865;4947.727874966911;4404.53752355998;4434.955650443454;4455.4876189238;4477.395551600557;4507.5267096955;5675.744591493392;5717.2281741965135;5740.425957404226;5767.336756273083;5815.505682016991;6243.5789902077295;6281.277653069864;6288.182536270347;6288.213973253343;6310.684465776692;5627.240322556402;5815.88368232633;5816.621919575214;5821.243238378984;5860.648361098348;4703.894584772575;5342.91985620216;5355.3305836770405;5402.945970540244;5429.104197807396;6271.310155882849;6285.560010799291;6292.937070629327;6298.370162983665;6303.810885673437;6137.22377000893;6190.597611155261;6238.670245960854;6287.559870808999;6323.525735569942;6172.753089876354;6276.497880169667;6297.622142671455;6311.27420346666;6319.368063193828;5735.910177066344;6217.844553886189;6221.595596286227;6233.812985610439;6250.124982502544;6057.485113765203;6107.735039974651;6221.751129954751;6271.811845644638;6284.528660350401;6141.946315431794;6152.292486363946;6162.907556386568;6168.506519478521;6182.196314479089;6150.138980542817;6165.229346331708;6165.32181460048;6182.783602573888;6183.81448556544;5730.398308177581;5803.622764520293;5915.645061296336;5964.31410387811;5993.940060599338;5011.924821127613;5990.251218597676;6095.952232173902;6139.079138129137;6143.047827586731;5999.5500337473995;6001.399860013964;6003.189569408249;6003.939960600513;6013.30846952606 23:12:05 INFO - PROCESS | 1919 | |1;ext.html;463.87243508035306;481.3021119376904;483.7557033697982;486.6252985201395;528.3738769558073;32297.031089067335;33117.172070698245;33224.83387583065;42233.94415139623;43046;44588.55411445884;44883.551164488345;44934.7753261234;45157.54842451574;45171.77414112933;8793.340499462534;9655;9723.17353024992;9915.553800079013;9927.652532161375;8729.170728780755;8893.644254229823;9748.36635618686;11810.940945295262;11959;10149.644762433316;12018.15872888898;12058.758824823499;12090.516379344816;12096.21374610652;11956.880431195663;12015.699607509794;12028.879711202864;12029.639110826689;12034.819477707753;8682.218600325938;10471.638686970722;11315.717107072307;11605.361705106216;12112.939435302855;43044.78477607592;43178.35232471515;43369.56630433718;43418.78290608527;43460.78269608664;3062.7856050076502;3728.9067773305615;3784.2620688965662;3789.2042671039117;4222.429971953778;32155.51766723501;32473.675263247536;32559.6744032559;32598;33406.3318733624;3279.8688052477873;3293.143782616513;3293.2919422324317;3305.140663427502;3403.5745531808525;558233;560030;560241;560311;560521;62429.687851560455;62744.745105097645;62780.686096569225;62895.6855215721;62929.370706293266;35378.469322960176;35718.107047324025;35773.28453430917;37564.62435375611;37577.81211093982;16806.243719032675;16831.579210519834;16996.49010529686;17001.489955301113;17031.574210644587;11719.238249513754;11891.940540297415;12916.483340666457;12997.15518491295;13005.284709340887;19744.506387340145;19867.403977880687;19875.900620497094;20539.897300513403;20563.897180514003;6782.966085169543;8034;8037.758867233875;8097.797555061171;8113.432059755878;922.995385023084;1018.6791160784329;1060.8514807926897;1075.596651255779;1115.6597237842536;30666.23334416613;30792.69207307943;30858.228544286572;31396.843015784776;31574;1065.4885654885697;1144.7710457908452;1145.7765735681628;1160.988390116105;1169.2399940039043;15677.608059798367;16056.116913569633;16087.839121608868;16095.597610059607;16450;574250;679561;683939;684796;685184;61510.69244653748;61940.69029654823;61974.690126549984;62016.68991655013;62053.68973155105;1258.924464532131;1371.8285214348207;1392.6657602175608;1417.758980973237;1426.4793350426812;30031.099067028023;31316.74733010654;31576.368472629958;31617.20956976094;32291.838540808087;51956.74021629868;52560.94878102493;52580;52636.210456843946;52638.21042684286;546392;565498;566846;567571.1621441867;568275.2689892357;24214.636780447956;24452.57663927016;24453.87773061123;25267.494650106528;25324;45986.54013459956;49875.501244987085;50333.49666503288;52476.73761631167;53385.46614533805;36190.09524761902;40641.1871762569;41366.1726765457;42277.5772242286;42340;40257;40291.395629065;40534.594654054265;41483.585164147975;41542.75371738853;39309.80345098371;39495;39637.2072558553;39863.80068099757;40222.99442513961;31121.519126392763;34019.31961360809;35276.76531321494;35662.28675426425;35801.46297805483;23933;24447.26658200257;25287.494250115262;25751;26306.342341441617 23:12:05 INFO - PROCESS | 1919 | |2;jquery.html;378.1944458303813;383.6834611445558;384.49055002122196;399.9840006399744;402.0631927608672;19946.301879434224;20520.179192832307;20879.895600521995;20918.686219706717;21010.264640737558;20662.48343791406;21006.89496552519;21074;22142.446438838997;22166.88916555419;5920.526357891369;6012.158297838306;6143.692815359236;6172.105044768528;6208.81373558794;4226.873193804191;4460.531644177363;6118.663473508956;6506.3818937200895;6847.486438517106;6733.461323094165;6794.898076528856;6810.659467026654;6815.659217039153;6822.454203663719;6809.625470599115;6812.216595091553;6825.044493770876;6836.384725374715;6840.418564422005;5743.1385292206505;5817.796377126822;5881.500072493843;5890.440408161217;5895.528357731371;27118.864405677847;27212.31969200766;27302;27560.862195688893;27579;2494.8128890333296;2565.1278565287707;2600.2719238613217;2613.7908967282574;3059.602251707286;15577.376904923789;15901;15946.760798588031;16128.435504757277;16541;1875.456117725858;1895.772507299119;1912.2446633579798;1939.5151212196952;1958.8237058664788;75824.24175758281;76029.61985190093;76319;94599;116818.83181168165;9040.547972601344;10741.51663175159;10979.28634638746;11099.056580190612;11330.660080197607;13679.974001949695;13761.449542018407;13770.65573360654;13795.793063104062;13802.93098534521;8503.489790612583;8706.303495720327;8708.52103134321;8748.387612867165;8824.294056475466;8817.867731984026;8827;8834.955825220834;8944.329175311877;8953.865692014626;6584.572002819801;6723.310675729747;6732.528722989344;6743.629100399525;6744.76393326241;1529.8087739032621;1679.1184628070362;1741.2773698915062;1801.1174524482908;1835.9173837177093;946.4888959961681;994.8656931314325;1011.549860312154;1023.6468418395641;1030.1501261459325;18088.63822723538;22588.54822903533;22729.431764205696;22970;22987.88506057459;815.3355015662216;951.6431338248157;1009.8081364540714;1027.773889744255;1030.8917913243292;11936.164468487123;12023.939880300542;12144.453499592539;12219.083568732382;12262.26426414418;612114;619631;626601;634147.8292608508;635171;69540.60918781596;119100.61798763977;126238;128780.35609821891;132715.33642331726;938.863864739609;975.4196253229317;987.4865070163619;987.827130252203;1021.7956408718286;18890.52773680614;19838.702419464007;19845.801541984973;19879.503012424804;19941.401757947286;43584;51738.74130629473;55912.720436397554;57853.710731447754;58243;89219.10780892108;89456.552717236;90096;90327;91330;7163.64181790898;7541.849163016818;7553.509021913558;7817.491863028885;8436.029856566329;7342.632868356454;9434.48110353937;13386.19682819034;13582.25297608641;13710.862891370958;10189.796204076025;11762.58830940913;11879.426420827871;11954.462049207632;11973.042156627274;11636.825447618123;11709.47776789012;11811.763764724828;11848.466818992994;11987.940060299643;11832.290062596272;11955.342456164994;11980;12034.977026952807;12061.51753929868;8340.749777506684;8453.577321133796;8512.97844258693;8552.786180345542;8688.695895643623;10431.582736690447;11187.440627968406;11419.771604568028;11776.941115294369;11880 23:12:05 INFO - PROCESS | 1919 | |3;mootools.html;1534.14088110658;1586.8730501559876;1806.9638607227855;1936.622358640064;1959.549303660159;8683.869741953868;8974.282057435406;9168.908310916897;9179.036201198878;9182.219511341536;7928.207179282069;8164.387670924675;8185.508869467836;8261.710840120582;8967.730968070951;2144.2602302205714;2319.69843920291;2393.0547433763654;2403.471236328005;2404.350825277174;2386.427257458206;2389.283215035491;2410.8915098820594;2411.8914648840846;2415.722191947922;1968.9803101969012;2326.1858349577597;2397.304781613339;2401.2196036288187;2409.903603855844;2012.4566367080952;2321.9767802322012;2397.8441401309033;2403.483251101004;2406.7713567211085;2356.8703721295324;2370.7510711375403;2374.5132247889314;2383.97616023841;2408.1330720940446;9087.7273681789;9123.133302336268;9130.726078217664;9136.08639136081;9147.908520914772;5279.5512381447625;5496.972515137399;5500.339959204881;5506.063969125258;5510.6969116698565;3576.7138628909884;3645.125169959217;3725.9441108383394;3731.4514476492222;3736.8610367291603;1069.8127827630133;1096.5668560918473;1116.391566596207;1119.0823524709742;1121.6074373969127;12204.755904881853;12205.694857628541;12242.143049986504;12248.326342051183;12268.26390416569;4706.3646407735205;4707.357657896628;4771.165046116916;4881.8535443936735;4890.633202509826;10183.847242291371;10270.178385729123;10277.588896444207;10305.330153539995;10313.174946004301;5461.208124821878;5469.453054694499;5485.259489968883;5486.149646804752;5498.257735205776;4460.420145381079;4927.28554359623;4978.103941290602;4987.875303117378;5003.649744517848;3415.863365465403;3740.3454395481226;3979.1245925896255;4059.573744756817;4064.065264989069;1249.650097972569;1278.9872101278868;1283.6854970532092;1290.8128321393344;1295.391166151907;796.7569891183128;888.5157589113949;927.1146055516965;955.0878910640322;978.6574698855344;6081.236441431935;6187.535934804908;6201.56589038772;6238.875222495525;6243.937560624427;851.923326900582;864.8659457784054;942.528735632184;954.5656726189567;1012.8227560177087;4078.4494093297026;4093.570175131628;4095.385692146202;4149.792510374469;4151.626353628188;10430.217733669853;10783.892161078445;10870.293430927122;10881.727956801144;10949.671509854717;10459.424731639832;10854.403007834644;10855;10902.618408355827;10959.56161753521;8306.54314012735;8336.416450848503;8403.70587029451;8404.873926891101;8410.990681118305;5955.612885162451;5978.372270911492;5994.490468310242;6132.78535251264;6244.46922011616;8176.223258790405;9758.853617195606;10142.594296228068;10165.644202452879;10198.541065651914;10355.171586272929;10370.688879333633;10374.740631484274;10405.115565177044;10406.739831504274;7590.39276857839;7644.006279183671;7722.227777222233;7730.574818384818;7756.224377562198;8655.307575393828;8686.739397818075;8780.034196238536;8780.121987801169;8795.120487951153;7686.884696729442;7802.804929876798;7910.841783164189;7952.483088599222;7954.164812694584;7469.626518673936;7581.6209189541405;7581.848363032819;7738.342240909361;7772.106207786177;7192.42460603161;7246.565206087652;7246.963765181351;7252.238514956065;7272.745453909088;5008.84973450797;5138.8972220556125;5154.020736060136;5218.608604354613;5220.216967454912;5212.348456442945;5258.842234732964;5271.103912334989;5301.655392399482;5387.272718182995 23:12:05 INFO - PROCESS | 1919 | |4;prototype.html;305.58135354564246;313.0826677833947;314.1564898248203;319.2672815887539;322.33759623972725;5309.230161626562;5347.973260133699;5351.250824884514;5355.705436201008;5377.462253774621;4537.568930951555;4619.838305659303;4691.437027556698;4877.902441951159;5318.813841515548;3480.2343484433386;3841.9423708644395;4029.5970402959615;4063.817128229222;4063.9390409143884;3920.333543297632;4030.6573941214883;4036.5670187083456;4066.5323487798837;4079.8376860264107;3979.084810493588;3983.342748446503;4000.599940006005;4005.7596544207295;4016.49793775778;3619.873304434332;3687.557493100818;3789.6210378962023;4017.9754162688473;4058.127502586958;2979.553067039961;3310;3513.139280876176;3528.3648943190215;3528.6118526962014;4656.720596764205;5193.584513238932;5204.843854684365;5224.346956630421;5241.42344342122;4474.507804141542;4637.333533218379;4654;4665.580097791181;4688.436788041079;5470;5472.20653005312;5509.256250406184;5522.22688823565;5534.944650553483;3890.649841514249;3903.4340020697123;3912.4131380292897;3932.154586763831;3933.2723446162267;5619.634723742944;5761.481466667978;5862.677552734598;5865.941340586583;5868.2664666916635;3465.7054150396993;3911.78485183312;3929.390944403622;3937.5078115235597;3957.08986933007;3532.9293414131575;4038.697097717683;4041.939370909439;4050.3519436890547;4064.695147863922;4460.1525710114975;4633.4208223972;4641.327007583882;4643.605293550018;4654.627629789608;5199.376074871039;5269.947300526946;5554.750036248379;5626.352969408488;5662.886742265214;1193.26614132308;1346.4546858522315;1361.7889227169806;1373.9106958047696;1381.9101758385873;729.744589393708;795.2921899509441;799.7121036426881;848.2662496940102;849.9787505312293;4641.814327426932;5024.62315326352;5193.338696219156;5203.32356793622;5211.609129315317;5296.92054619181;5724.685142317129;5736.741846616912;5744.425557444222;5772.365039845614;4696.591022693178;4818.494058123917;5190.273361729361;5221.242919776687;5225.634205605571;2328.2316835444262;2889.4076714273524;2983;2983.1212879571317;2987.4323878463024;5901.881962360729;5935.910961335583;5948.226730525004;5962.25471816023;5970.641761494324;5133;5465.262189604432;5556.6104676147515;5875.059990401515;5901.878643057806;5385.695090336016;5686.317641882918;5856.882862342814;5869.530437564899;5902.881942361215;4853.538913803183;4860.76504475055;4963.4540200577185;5721.599488035798;5722.885542289214;4593.058423023205;5600.327960644749;5600.495955363874;5608;5660.15097735343;5853.560982926383;5857.121431785266;5910.079224252221;5935.970320148372;5993.101034844808;3782.451544525973;3916.2167566486246;3937.645611894943;3943.9688560391996;3948.04571736992;5322.8935421292135;5614.6631202128;5659.886802264014;5676.155249543832;5715.228444160151;5473.616846820684;5509.752061157177;5530.253415788817;5559.916601250903;5611.943880561143;5271.4464981176425;5511.641743286674;5521.613487055867;5542.9168562472405;5574.411292781538;5103.3159057511675;5201.42784293735;5355.3305836770405;5388.703621300866;5394.541463975606;4874.366332376911;5086.745662716776;5093.643444958817;5140.46025167367;5432.511074003359;4369.956300437082;4960.826371076995;4964.578010868972;5154.098032844162;5305.336832895888 23:12:05 INFO - PROCESS | 1919 | |5;yui.html;686.7012849410506;752.7177308509309;804.987925181122;817.525835015691;849.2017503546664;13284.269365184911;15891.84108158918;16438.260278287475;16536.66926661466;16539.58651033725;14544.981851270415;14708.779368309484;14716.484923027696;14717.779233311509;14908.18005009724;721.084223036743;740.2079774641136;884.2660591708882;901.4095767272447;902.9097090290984;890.5680744838749;895.7491902267343;896.6278994217392;899.9595018224163;909.849874770662;950.2635457520443;950.3062764182151;950.4154944709023;950.9714708558754;951.7430293820664;754.7999780058261;775.8099265679888;777.9688812447496;778.2101167315179;946.3990366117496;692.4737199728193;709.4466316273315;712.3161764705889;714.4820005496026;714.746265075901;15197.620059498491;15223.771643425358;15252.38990440381;15271.618209544738;15295.08229506222;13912.234827084507;13935.790963135561;13939.581812545639;13984.160950343012;14280.285985700673;12776;12969.935150324281;12990.740185196242;13266.204027758366;13279.336033198302;4467.128909862578;4571.72569645819;4576.679632425731;4638.791254393526;4690.366800481926;112204.87795122026;112894.48422063107;113139.43430282878;113270.86729132685;113397;11018.283811552223;11047.944760276147;11265.774684506265;11557.942210289062;11562.421878906021;21025.474363141046;21934.122635094736;21959.67060494094;21962.121515139534;22000.33998980033;6217.937820621736;7018;8024.598770061473;8120.512769233865;8163.428560000743;4143.399207114951;4547.272436410159;4696.81212751493;4770;4807.18277892759;3568.839402226906;3697.9445308320396;3730.3658378075793;3733.9813300933865;3739.700823934133;661.3716968879584;664.19963943449;676.0467740485891;683.7470136049693;700.7757517594321;619.891518984176;623.9968800155971;676.4993904510725;707.3669066185838;713.4969846258375;8040;9654.71035868925;9743.31796774219;9749.707508774749;9945.154661853823;497.56711660855285;666.6366830077619;684.5824047331124;691.408845437154;697.3479796390391;4674.182018146811;4789.70273732907;4791.616670666338;4817.8313759018265;4826.58973987215;97619.04761904723;118103;125526;153381.2330938338;160380;133087;134376;135045.64954350528;135609;135887;55001.72499137479;73288.63355683081;81987.3602527958;97259.54110688345;111587.97854096585;9885.406875587487;10043.497825108861;10193.898061019296;10217.948910255402;10229.641962531276;53125.7343713279;61844.69077654583;62657.12028639148;63070.684646576476;64516;92716;93057.06942930484;93833.65415864658;97278;99436;13438.529651462151;13656.684732385844;13690.315484225948;13699.931500342434;14075.936234312783;15151;15182.164980926154;15230.543083707507;15486.225688715744;15772.369105235663;14039.719205616035;14049.648758780704;14345;14642.780358294847;14753.483628072965;14105.506307279196;14270.354900882438;14355.784663229852;14365.569032929028;14657.780133298222;13508.324583770576;13660.521881734488;14187.503437379642;14421.92789036048;14424;10943.398113103856;11213.887861121611;11473.254238474472;11520.827187592025;11860.110491713329;10699.255260658707;10910.509027093958;11416.793619499982;11780.234284771463;11782.17524773292 23:12:05 INFO - PROCESS | 1919 | __end_tp_report 23:12:05 INFO - PROCESS | 1919 | __start_cc_report 23:12:05 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,1054 23:12:05 INFO - PROCESS | 1919 | __end_cc_report 23:12:05 INFO - PROCESS | 1919 | __startTimestamp1468995125408__endTimestamp 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | ------- Summary: start ------- 23:12:05 INFO - PROCESS | 1919 | Number of tests: 6 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | [#0] dojo.html Cycles:165 Average:5742.79 Median:5990.25 stddev:670.87 (11.2%) stddev-sans-first:631.09 23:12:05 INFO - PROCESS | 1919 | Values: 2769.7 2833.6 3333.1 3361.0 3365.6 5610.2 5634.4 5701.4 5742.1 5778.6 5704.9 5739.2 5743.9 5750.2 5754.2 5174.8 5212.8 5214.6 5255.2 5294.9 5275.2 5285.9 5293.0 5295.5 5299.4 5179.1 5202.0 5211.1 5211.3 5293.4 5275.8 5290.4 5299.2 5312.9 5329.1 5087.2 5092.9 5105.7 5221.4 5231.1 5639.5 5653.0 5690.7 5701.7 5709.4 6237.2 6253.9 6256.2 6268.2 6281.3 6055.2 6222.6 6295.9 6298.8 6312.0 5829.4 5858.7 5862.7 5888.7 5891.9 6263.3 6281.4 6282.3 6285.0 6301.4 5983.5 6092.6 6097.1 6143.7 6174.8 6283.1 6283.4 6286.8 6294.3 6310.2 6183.3 6259.7 6267.5 6279.0 6282.7 6086.1 6107.0 6145.0 6194.8 6223.8 4853.7 4903.1 4916.5 4917.1 4947.7 4404.5 4435.0 4455.5 4477.4 4507.5 5675.7 5717.2 5740.4 5767.3 5815.5 6243.6 6281.3 6288.2 6288.2 6310.7 5627.2 5815.9 5816.6 5821.2 5860.6 4703.9 5342.9 5355.3 5402.9 5429.1 6271.3 6285.6 6292.9 6298.4 6303.8 6137.2 6190.6 6238.7 6287.6 6323.5 6172.8 6276.5 6297.6 6311.3 6319.4 5735.9 6217.8 6221.6 6233.8 6250.1 6057.5 6107.7 6221.8 6271.8 6284.5 6141.9 6152.3 6162.9 6168.5 6182.2 6150.1 6165.2 6165.3 6182.8 6183.8 5730.4 5803.6 5915.6 5964.3 5993.9 5011.9 5990.3 6096.0 6139.1 6143.0 5999.6 6001.4 6003.2 6003.9 6013.3 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | [#1] ext.html Cycles:180 Average:72572.12 Median:35787.37 stddev:159621.63 (446.0%) stddev-sans-first:159977.60 23:12:05 INFO - PROCESS | 1919 | Values: 463.9 481.3 483.8 486.6 528.4 32297.0 33117.2 33224.8 42233.9 43046.0 44588.6 44883.6 44934.8 45157.5 45171.8 8793.3 9655.0 9723.2 9915.6 9927.7 8729.2 8893.6 9748.4 11810.9 11959.0 10149.6 12018.2 12058.8 12090.5 12096.2 11956.9 12015.7 12028.9 12029.6 12034.8 8682.2 10471.6 11315.7 11605.4 12112.9 43044.8 43178.4 43369.6 43418.8 43460.8 3062.8 3728.9 3784.3 3789.2 4222.4 32155.5 32473.7 32559.7 32598.0 33406.3 3279.9 3293.1 3293.3 3305.1 3403.6 558233.0 560030.0 560241.0 560311.0 560521.0 62429.7 62744.7 62780.7 62895.7 62929.4 35378.5 35718.1 35773.3 37564.6 37577.8 16806.2 16831.6 16996.5 17001.5 17031.6 11719.2 11891.9 12916.5 12997.2 13005.3 19744.5 19867.4 19875.9 20539.9 20563.9 6783.0 8034.0 8037.8 8097.8 8113.4 923.0 1018.7 1060.9 1075.6 1115.7 30666.2 30792.7 30858.2 31396.8 31574.0 1065.5 1144.8 1145.8 1161.0 1169.2 15677.6 16056.1 16087.8 16095.6 16450.0 574250.0 679561.0 683939.0 684796.0 685184.0 61510.7 61940.7 61974.7 62016.7 62053.7 1258.9 1371.8 1392.7 1417.8 1426.5 30031.1 31316.7 31576.4 31617.2 32291.8 51956.7 52560.9 52580.0 52636.2 52638.2 546392.0 565498.0 566846.0 567571.2 568275.3 24214.6 24452.6 24453.9 25267.5 25324.0 45986.5 49875.5 50333.5 52476.7 53385.5 36190.1 40641.2 41366.2 42277.6 42340.0 40257.0 40291.4 40534.6 41483.6 41542.8 39309.8 39495.0 39637.2 39863.8 40223.0 31121.5 34019.3 35276.8 35662.3 35801.5 23933.0 24447.3 25287.5 25751.0 26306.3 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | [#2] jquery.html Cycles:180 Average:35423.97 Median:27431.43 stddev:103494.54 (377.3%) stddev-sans-first:103751.41 23:12:05 INFO - PROCESS | 1919 | Values: 378.2 383.7 384.5 400.0 402.1 19946.3 20520.2 20879.9 20918.7 21010.3 20662.5 21006.9 21074.0 22142.4 22166.9 5920.5 6012.2 6143.7 6172.1 6208.8 4226.9 4460.5 6118.7 6506.4 6847.5 6733.5 6794.9 6810.7 6815.7 6822.5 6809.6 6812.2 6825.0 6836.4 6840.4 5743.1 5817.8 5881.5 5890.4 5895.5 27118.9 27212.3 27302.0 27560.9 27579.0 2494.8 2565.1 2600.3 2613.8 3059.6 15577.4 15901.0 15946.8 16128.4 16541.0 1875.5 1895.8 1912.2 1939.5 1958.8 75824.2 76029.6 76319.0 94599.0 116818.8 9040.5 10741.5 10979.3 11099.1 11330.7 13680.0 13761.4 13770.7 13795.8 13802.9 8503.5 8706.3 8708.5 8748.4 8824.3 8817.9 8827.0 8835.0 8944.3 8953.9 6584.6 6723.3 6732.5 6743.6 6744.8 1529.8 1679.1 1741.3 1801.1 1835.9 946.5 994.9 1011.5 1023.6 1030.2 18088.6 22588.5 22729.4 22970.0 22987.9 815.3 951.6 1009.8 1027.8 1030.9 11936.2 12023.9 12144.5 12219.1 12262.3 612114.0 619631.0 626601.0 634147.8 635171.0 69540.6 119100.6 126238.0 128780.4 132715.3 938.9 975.4 987.5 987.8 1021.8 18890.5 19838.7 19845.8 19879.5 19941.4 43584.0 51738.7 55912.7 57853.7 58243.0 89219.1 89456.6 90096.0 90327.0 91330.0 7163.6 7541.8 7553.5 7817.5 8436.0 7342.6 9434.5 13386.2 13582.3 13710.9 10189.8 11762.6 11879.4 11954.5 11973.0 11636.8 11709.5 11811.8 11848.5 11987.9 11832.3 11955.3 11980.0 12035.0 12061.5 8340.7 8453.6 8513.0 8552.8 8688.7 10431.6 11187.4 11419.8 11776.9 11880.0 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | [#3] mootools.html Cycles:180 Average:5855.71 Median:4952.69 stddev:3279.16 (66.2%) stddev-sans-first:3272.27 23:12:05 INFO - PROCESS | 1919 | Values: 1534.1 1586.9 1807.0 1936.6 1959.5 8683.9 8974.3 9168.9 9179.0 9182.2 7928.2 8164.4 8185.5 8261.7 8967.7 2144.3 2319.7 2393.1 2403.5 2404.4 2386.4 2389.3 2410.9 2411.9 2415.7 1969.0 2326.2 2397.3 2401.2 2409.9 2012.5 2322.0 2397.8 2403.5 2406.8 2356.9 2370.8 2374.5 2384.0 2408.1 9087.7 9123.1 9130.7 9136.1 9147.9 5279.6 5497.0 5500.3 5506.1 5510.7 3576.7 3645.1 3725.9 3731.5 3736.9 1069.8 1096.6 1116.4 1119.1 1121.6 12204.8 12205.7 12242.1 12248.3 12268.3 4706.4 4707.4 4771.2 4881.9 4890.6 10183.8 10270.2 10277.6 10305.3 10313.2 5461.2 5469.5 5485.3 5486.1 5498.3 4460.4 4927.3 4978.1 4987.9 5003.6 3415.9 3740.3 3979.1 4059.6 4064.1 1249.7 1279.0 1283.7 1290.8 1295.4 796.8 888.5 927.1 955.1 978.7 6081.2 6187.5 6201.6 6238.9 6243.9 851.9 864.9 942.5 954.6 1012.8 4078.4 4093.6 4095.4 4149.8 4151.6 10430.2 10783.9 10870.3 10881.7 10949.7 10459.4 10854.4 10855.0 10902.6 10959.6 8306.5 8336.4 8403.7 8404.9 8411.0 5955.6 5978.4 5994.5 6132.8 6244.5 8176.2 9758.9 10142.6 10165.6 10198.5 10355.2 10370.7 10374.7 10405.1 10406.7 7590.4 7644.0 7722.2 7730.6 7756.2 8655.3 8686.7 8780.0 8780.1 8795.1 7686.9 7802.8 7910.8 7952.5 7954.2 7469.6 7581.6 7581.8 7738.3 7772.1 7192.4 7246.6 7247.0 7252.2 7272.7 5008.8 5138.9 5154.0 5218.6 5220.2 5212.3 5258.8 5271.1 5301.7 5387.3 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | [#4] prototype.html Cycles:180 Average:4522.55 Median:5136.73 stddev:1383.81 (26.9%) stddev-sans-first:1351.01 23:12:05 INFO - PROCESS | 1919 | Values: 305.6 313.1 314.2 319.3 322.3 5309.2 5348.0 5351.3 5355.7 5377.5 4537.6 4619.8 4691.4 4877.9 5318.8 3480.2 3841.9 4029.6 4063.8 4063.9 3920.3 4030.7 4036.6 4066.5 4079.8 3979.1 3983.3 4000.6 4005.8 4016.5 3619.9 3687.6 3789.6 4018.0 4058.1 2979.6 3310.0 3513.1 3528.4 3528.6 4656.7 5193.6 5204.8 5224.3 5241.4 4474.5 4637.3 4654.0 4665.6 4688.4 5470.0 5472.2 5509.3 5522.2 5534.9 3890.6 3903.4 3912.4 3932.2 3933.3 5619.6 5761.5 5862.7 5865.9 5868.3 3465.7 3911.8 3929.4 3937.5 3957.1 3532.9 4038.7 4041.9 4050.4 4064.7 4460.2 4633.4 4641.3 4643.6 4654.6 5199.4 5269.9 5554.8 5626.4 5662.9 1193.3 1346.5 1361.8 1373.9 1381.9 729.7 795.3 799.7 848.3 850.0 4641.8 5024.6 5193.3 5203.3 5211.6 5296.9 5724.7 5736.7 5744.4 5772.4 4696.6 4818.5 5190.3 5221.2 5225.6 2328.2 2889.4 2983.0 2983.1 2987.4 5901.9 5935.9 5948.2 5962.3 5970.6 5133.0 5465.3 5556.6 5875.1 5901.9 5385.7 5686.3 5856.9 5869.5 5902.9 4853.5 4860.8 4963.5 5721.6 5722.9 4593.1 5600.3 5600.5 5608.0 5660.2 5853.6 5857.1 5910.1 5936.0 5993.1 3782.5 3916.2 3937.6 3944.0 3948.0 5322.9 5614.7 5659.9 5676.2 5715.2 5473.6 5509.8 5530.3 5559.9 5611.9 5271.4 5511.6 5521.6 5542.9 5574.4 5103.3 5201.4 5355.3 5388.7 5394.5 4874.4 5086.7 5093.6 5140.5 5432.5 4370.0 4960.8 4964.6 5154.1 5305.3 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - PROCESS | 1919 | [#5] yui.html Cycles:180 Average:24194.03 Median:3714.16 stddev:37774.76 (1017.0%) stddev-sans-first:37839.49 23:12:05 INFO - PROCESS | 1919 | Values: 686.7 752.7 805.0 817.5 849.2 13284.3 15891.8 16438.3 16536.7 16539.6 14545.0 14708.8 14716.5 14717.8 14908.2 721.1 740.2 884.3 901.4 902.9 890.6 895.7 896.6 900.0 909.8 950.3 950.3 950.4 951.0 951.7 754.8 775.8 778.0 778.2 946.4 692.5 709.4 712.3 714.5 714.7 15197.6 15223.8 15252.4 15271.6 15295.1 13912.2 13935.8 13939.6 13984.2 14280.3 12776.0 12969.9 12990.7 13266.2 13279.3 4467.1 4571.7 4576.7 4638.8 4690.4 112204.9 112894.5 113139.4 113270.9 113397.0 11018.3 11047.9 11265.8 11557.9 11562.4 21025.5 21934.1 21959.7 21962.1 22000.3 6217.9 7018.0 8024.6 8120.5 8163.4 4143.4 4547.3 4696.8 4770.0 4807.2 3568.8 3697.9 3730.4 3734.0 3739.7 661.4 664.2 676.0 683.7 700.8 619.9 624.0 676.5 707.4 713.5 8040.0 9654.7 9743.3 9749.7 9945.2 497.6 666.6 684.6 691.4 697.3 4674.2 4789.7 4791.6 4817.8 4826.6 97619.0 118103.0 125526.0 153381.2 160380.0 133087.0 134376.0 135045.6 135609.0 135887.0 55001.7 73288.6 81987.4 97259.5 111588.0 9885.4 10043.5 10193.9 10217.9 10229.6 53125.7 61844.7 62657.1 63070.7 64516.0 92716.0 93057.1 93833.7 97278.0 99436.0 13438.5 13656.7 13690.3 13699.9 14075.9 15151.0 15182.2 15230.5 15486.2 15772.4 14039.7 14049.6 14345.0 14642.8 14753.5 14105.5 14270.4 14355.8 14365.6 14657.8 13508.3 13660.5 14187.5 14421.9 14424.0 10943.4 11213.9 11473.3 11520.8 11860.1 10699.3 10910.5 11416.8 11780.2 11782.2 23:12:05 INFO - PROCESS | 1919 | -------- Summary: end -------- 23:12:05 INFO - PROCESS | 1919 | 23:12:05 INFO - TEST-INFO | 1919: exit 0 23:12:06 INFO - TEST-OK | dromaeo_css | took 1116049ms 23:12:06 INFO - TEST-START | kraken 23:12:06 INFO - operating with platform_type : mac_ 23:12:06 INFO - Initialising browser for kraken test... 23:12:06 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpNSdCvh/profile http://localhost:54499/getInfo.html) 23:12:08 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 23:12:08 INFO - PROCESS | 1923 | colorDepth:24 23:12:08 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 23:12:08 INFO - PROCESS | 1923 | __metrics 23:12:08 INFO - PROCESS | 1923 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:12:08 INFO - PROCESS | 1923 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:12:09 INFO - TEST-INFO | 1923: exit 0 23:12:09 INFO - Browser initialized. 23:12:09 INFO - Running cycle 1/1 for kraken test... 23:12:09 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpNSdCvh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:20 INFO - PROCESS | 1924 | RSS: Main: 245166080 23:12:20 INFO - PROCESS | 1924 | 23:12:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54499/tests/kraken/driver.html?testName=audio-beat-detection) 23:12:24 INFO - PROCESS | 1924 | RSS: Main: 294260736 23:12:24 INFO - PROCESS | 1924 | 23:12:27 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54499/tests/kraken/driver.html?testName=audio-dft) 23:12:27 INFO - PROCESS | 1924 | RSS: Main: 304439296 23:12:27 INFO - PROCESS | 1924 | 23:12:31 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54499/tests/kraken/driver.html?testName=audio-fft) 23:12:31 INFO - PROCESS | 1924 | RSS: Main: 389836800 23:12:31 INFO - PROCESS | 1924 | 23:12:33 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54499/tests/kraken/driver.html?testName=audio-oscillator) 23:12:34 INFO - PROCESS | 1924 | RSS: Main: 293761024 23:12:34 INFO - PROCESS | 1924 | 23:12:36 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54499/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:12:37 INFO - PROCESS | 1924 | RSS: Main: 297910272 23:12:37 INFO - PROCESS | 1924 | 23:12:39 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54499/tests/kraken/driver.html?testName=imaging-darkroom) 23:12:40 INFO - PROCESS | 1924 | RSS: Main: 333742080 23:12:40 INFO - PROCESS | 1924 | 23:12:43 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54499/tests/kraken/driver.html?testName=imaging-desaturate) 23:12:44 INFO - PROCESS | 1924 | RSS: Main: 336814080 23:12:44 INFO - PROCESS | 1924 | 23:12:46 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54499/tests/kraken/driver.html?testName=json-parse-financial) 23:12:47 INFO - PROCESS | 1924 | RSS: Main: 336654336 23:12:47 INFO - PROCESS | 1924 | 23:12:48 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54499/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:12:49 INFO - PROCESS | 1924 | RSS: Main: 313720832 23:12:49 INFO - PROCESS | 1924 | 23:12:51 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:12:52 INFO - PROCESS | 1924 | RSS: Main: 320557056 23:12:52 INFO - PROCESS | 1924 | 23:12:54 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:12:55 INFO - PROCESS | 1924 | RSS: Main: 319967232 23:12:55 INFO - PROCESS | 1924 | 23:12:57 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:12:58 INFO - PROCESS | 1924 | RSS: Main: 326709248 23:12:58 INFO - PROCESS | 1924 | 23:13:01 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:13:02 INFO - PROCESS | 1924 | RSS: Main: 330002432 23:13:02 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:54499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54499/tests/kraken/driver.html?testName=ai-astar) 23:13:04 INFO - PROCESS | 1924 | RSS: Main: 373616640 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | __start_tp_report 23:13:04 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 23:13:04 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 23:13:04 INFO - PROCESS | 1924 | |i|pagename|runs| 23:13:04 INFO - PROCESS | 1924 | |0;ai-astar;102;116;114;114;115;113;113;113;112;114 23:13:04 INFO - PROCESS | 1924 | |1;audio-beat-detection;141;132;131;155;106;127;123;108;156;107 23:13:04 INFO - PROCESS | 1924 | |2;audio-dft;181;188;185;183;183;187;184;181;184;185 23:13:04 INFO - PROCESS | 1924 | |3;audio-fft;77;72;56;88;76;68;61;66;82;57 23:13:04 INFO - PROCESS | 1924 | |4;audio-oscillator;84;92;93;71;71;67;97;70;68;102 23:13:04 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;89;88;90;87;89;86;90;87;89;85 23:13:04 INFO - PROCESS | 1924 | |6;imaging-darkroom;94;95;96;95;94;95;95;95;94;95 23:13:04 INFO - PROCESS | 1924 | |7;imaging-desaturate;87;86;86;84;85;89;87;84;86;90 23:13:04 INFO - PROCESS | 1924 | |8;json-parse-financial;52;52;52;52;52;54;52;52;53;52 23:13:04 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;66;70;70;70;74;73;70;74;80;103 23:13:04 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;83;82;82;86;85;84;83;86;83;83 23:13:04 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;137;141;152;156;149;133;133;136;217;135 23:13:04 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;169;170;170;170;167;169;185;152;216;221 23:13:04 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;69;67;69;69;80;87;85;85;70;65 23:13:04 INFO - PROCESS | 1924 | __end_tp_report 23:13:04 INFO - PROCESS | 1924 | __start_cc_report 23:13:04 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,2032 23:13:04 INFO - PROCESS | 1924 | __end_cc_report 23:13:04 INFO - PROCESS | 1924 | __startTimestamp1468995184258__endTimestamp 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | ------- Summary: start ------- 23:13:04 INFO - PROCESS | 1924 | Number of tests: 14 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.89 (3.4%) stddev-sans-first:1.20 23:13:04 INFO - PROCESS | 1924 | Values: 102.0 116.0 114.0 114.0 115.0 113.0 113.0 113.0 112.0 114.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:131.50 stddev:18.42 (14.0%) stddev-sans-first:18.99 23:13:04 INFO - PROCESS | 1924 | Values: 141.0 132.0 131.0 155.0 106.0 127.0 123.0 108.0 156.0 107.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:2.28 (1.2%) stddev-sans-first:2.13 23:13:04 INFO - PROCESS | 1924 | Values: 181.0 188.0 185.0 183.0 183.0 187.0 184.0 181.0 184.0 185.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:70.30 Median:74.00 stddev:10.66 (14.4%) stddev-sans-first:11.02 23:13:04 INFO - PROCESS | 1924 | Values: 77.0 72.0 56.0 88.0 76.0 68.0 61.0 66.0 82.0 57.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:77.50 stddev:13.56 (17.5%) stddev-sans-first:14.35 23:13:04 INFO - PROCESS | 1924 | Values: 84.0 92.0 93.0 71.0 71.0 67.0 97.0 70.0 68.0 102.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:1.70 (1.9%) stddev-sans-first:1.76 23:13:04 INFO - PROCESS | 1924 | Values: 89.0 88.0 90.0 87.0 89.0 86.0 90.0 87.0 89.0 85.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 23:13:04 INFO - PROCESS | 1924 | Values: 94.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:86.50 stddev:1.96 (2.3%) stddev-sans-first:2.06 23:13:04 INFO - PROCESS | 1924 | Values: 87.0 86.0 86.0 84.0 85.0 89.0 87.0 84.0 86.0 90.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 23:13:04 INFO - PROCESS | 1924 | Values: 52.0 52.0 52.0 52.0 52.0 54.0 52.0 52.0 53.0 52.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:75.00 Median:71.50 stddev:10.52 (14.7%) stddev-sans-first:10.64 23:13:04 INFO - PROCESS | 1924 | Values: 66.0 70.0 70.0 70.0 74.0 73.0 70.0 74.0 80.0 103.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.50 stddev:1.49 (1.8%) stddev-sans-first:1.56 23:13:04 INFO - PROCESS | 1924 | Values: 83.0 82.0 82.0 86.0 85.0 84.0 83.0 86.0 83.0 83.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:145.00 stddev:25.31 (17.5%) stddev-sans-first:26.48 23:13:04 INFO - PROCESS | 1924 | Values: 137.0 141.0 152.0 156.0 149.0 133.0 133.0 136.0 217.0 135.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:170.00 stddev:22.32 (13.1%) stddev-sans-first:23.39 23:13:04 INFO - PROCESS | 1924 | Values: 169.0 170.0 170.0 170.0 167.0 169.0 185.0 152.0 216.0 221.0 23:13:04 INFO - PROCESS | 1924 | 23:13:04 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:75.00 stddev:8.59 (11.5%) stddev-sans-first:8.87 23:13:04 INFO - PROCESS | 1924 | Values: 69.0 67.0 69.0 69.0 80.0 87.0 85.0 85.0 70.0 65.0 23:13:04 INFO - PROCESS | 1924 | -------- Summary: end -------- 23:13:04 INFO - PROCESS | 1924 | 23:13:05 INFO - TEST-INFO | 1924: exit 0 23:13:05 INFO - TEST-OK | kraken | took 59078ms 23:13:05 INFO - SUITE-END | took 1175s 23:13:05 INFO - Completed test suite (00:19:35) 23:13:05 INFO - javascript benchmark 23:13:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2769.6676398832137, 2833.631627888374, 3333.100062982993, 3361.0421030006455, 3365.5961284645846, 5610.24261724667, 5634.3802181759975, 5701.372848986608, 5742.138679198122, 5778.566607504438, 5704.942950570493, 5739.196512488255, 5743.885122297552, 5750.166225897243, 5754.1944127822135, 5174.818881339144, 5212.765425555859, 5214.556762675177, 5255.237990491377, 5294.947050529503, 5275.182346736243, 5285.867853303679, 5293.04725149473, 5295.549878260353, 5299.390570084451, 5179.093658609747, 5202.0, 5211.088059589576, 5211.296474975868, 5293.3647962244395, 5275.762590683428, 5290.39160496544, 5299.231611416361, 5312.946870531284, 5329.067413202674, 5087.16061849798, 5092.949070509285, 5105.719185444799, 5221.425643179247, 5231.058409486291, 5639.4642508962, 5653.010723123437, 5690.7439165237665, 5701.6864072476, 5709.371969083398, 6237.189165408513, 6253.937460625381, 6256.217972753406, 6268.15379923709, 6281.309056003836, 6055.222517765706, 6222.595531290497, 6295.905561416583, 6298.779542715983, 6312.021636646281, 5829.446202610745, 5858.677772722456, 5862.677552734641, 5888.676122813201, 5891.911621325684, 6263.279722831932, 6281.403266689657, 6282.340354262828, 6285.025820997753, 6301.432871041537, 5983.461488466059, 6092.573519853657, 6097.050200442776, 6143.692815359214, 6174.8147555573405, 6283.057541368831, 6283.434490895841, 6286.811395658137, 6294.339094395114, 6310.1796766420075, 6183.258009038944, 6259.6870156492, 6267.4986001119805, 6279.0, 6282.685865706696, 6086.1175129606845, 6107.006761825774, 6144.972159187547, 6194.752209911643, 6223.782167624111, 4853.708777473363, 4903.092927808367, 4916.483769204254, 4917.1149193144865, 4947.727874966911, 4404.53752355998, 4434.955650443454, 4455.4876189238, 4477.395551600557, 4507.5267096955, 5675.744591493392, 5717.2281741965135, 5740.425957404226, 5767.336756273083, 5815.505682016991, 6243.5789902077295, 6281.277653069864, 6288.182536270347, 6288.213973253343, 6310.684465776692, 5627.240322556402, 5815.88368232633, 5816.621919575214, 5821.243238378984, 5860.648361098348, 4703.894584772575, 5342.91985620216, 5355.3305836770405, 5402.945970540244, 5429.104197807396, 6271.310155882849, 6285.560010799291, 6292.937070629327, 6298.370162983665, 6303.810885673437, 6137.22377000893, 6190.597611155261, 6238.670245960854, 6287.559870808999, 6323.525735569942, 6172.753089876354, 6276.497880169667, 6297.622142671455, 6311.27420346666, 6319.368063193828, 5735.910177066344, 6217.844553886189, 6221.595596286227, 6233.812985610439, 6250.124982502544, 6057.485113765203, 6107.735039974651, 6221.751129954751, 6271.811845644638, 6284.528660350401, 6141.946315431794, 6152.292486363946, 6162.907556386568, 6168.506519478521, 6182.196314479089, 6150.138980542817, 6165.229346331708, 6165.32181460048, 6182.783602573888, 6183.81448556544, 5730.398308177581, 5803.622764520293, 5915.645061296336, 5964.31410387811, 5993.940060599338, 5011.924821127613, 5990.251218597676, 6095.952232173902, 6139.079138129137, 6143.047827586731, 5999.5500337473995, 6001.399860013964, 6003.189569408249, 6003.939960600513, 6013.30846952606], "value": 5696.079716373702, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.87243508035306, 481.3021119376904, 483.7557033697982, 486.6252985201395, 528.3738769558073, 32297.031089067335, 33117.172070698245, 33224.83387583065, 42233.94415139623, 43046.0, 44588.55411445884, 44883.551164488345, 44934.7753261234, 45157.54842451574, 45171.77414112933, 8793.340499462534, 9655.0, 9723.17353024992, 9915.553800079013, 9927.652532161375, 8729.170728780755, 8893.644254229823, 9748.36635618686, 11810.940945295262, 11959.0, 10149.644762433316, 12018.15872888898, 12058.758824823499, 12090.516379344816, 12096.21374610652, 11956.880431195663, 12015.699607509794, 12028.879711202864, 12029.639110826689, 12034.819477707753, 8682.218600325938, 10471.638686970722, 11315.717107072307, 11605.361705106216, 12112.939435302855, 43044.78477607592, 43178.35232471515, 43369.56630433718, 43418.78290608527, 43460.78269608664, 3062.7856050076502, 3728.9067773305615, 3784.2620688965662, 3789.2042671039117, 4222.429971953778, 32155.51766723501, 32473.675263247536, 32559.6744032559, 32598.0, 33406.3318733624, 3279.8688052477873, 3293.143782616513, 3293.2919422324317, 3305.140663427502, 3403.5745531808525, 558233.0, 560030.0, 560241.0, 560311.0, 560521.0, 62429.687851560455, 62744.745105097645, 62780.686096569225, 62895.6855215721, 62929.370706293266, 35378.469322960176, 35718.107047324025, 35773.28453430917, 37564.62435375611, 37577.81211093982, 16806.243719032675, 16831.579210519834, 16996.49010529686, 17001.489955301113, 17031.574210644587, 11719.238249513754, 11891.940540297415, 12916.483340666457, 12997.15518491295, 13005.284709340887, 19744.506387340145, 19867.403977880687, 19875.900620497094, 20539.897300513403, 20563.897180514003, 6782.966085169543, 8034.0, 8037.758867233875, 8097.797555061171, 8113.432059755878, 922.995385023084, 1018.6791160784329, 1060.8514807926897, 1075.596651255779, 1115.6597237842536, 30666.23334416613, 30792.69207307943, 30858.228544286572, 31396.843015784776, 31574.0, 1065.4885654885697, 1144.7710457908452, 1145.7765735681628, 1160.988390116105, 1169.2399940039043, 15677.608059798367, 16056.116913569633, 16087.839121608868, 16095.597610059607, 16450.0, 574250.0, 679561.0, 683939.0, 684796.0, 685184.0, 61510.69244653748, 61940.69029654823, 61974.690126549984, 62016.68991655013, 62053.68973155105, 1258.924464532131, 1371.8285214348207, 1392.6657602175608, 1417.758980973237, 1426.4793350426812, 30031.099067028023, 31316.74733010654, 31576.368472629958, 31617.20956976094, 32291.838540808087, 51956.74021629868, 52560.94878102493, 52580.0, 52636.210456843946, 52638.21042684286, 546392.0, 565498.0, 566846.0, 567571.1621441867, 568275.2689892357, 24214.636780447956, 24452.57663927016, 24453.87773061123, 25267.494650106528, 25324.0, 45986.54013459956, 49875.501244987085, 50333.49666503288, 52476.73761631167, 53385.46614533805, 36190.09524761902, 40641.1871762569, 41366.1726765457, 42277.5772242286, 42340.0, 40257.0, 40291.395629065, 40534.594654054265, 41483.585164147975, 41542.75371738853, 39309.80345098371, 39495.0, 39637.2072558553, 39863.80068099757, 40222.99442513961, 31121.519126392763, 34019.31961360809, 35276.76531321494, 35662.28675426425, 35801.46297805483, 23933.0, 24447.26658200257, 25287.494250115262, 25751.0, 26306.342341441617], "value": 20590.594983040115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.1944458303813, 383.6834611445558, 384.49055002122196, 399.9840006399744, 402.0631927608672, 19946.301879434224, 20520.179192832307, 20879.895600521995, 20918.686219706717, 21010.264640737558, 20662.48343791406, 21006.89496552519, 21074.0, 22142.446438838997, 22166.88916555419, 5920.526357891369, 6012.158297838306, 6143.692815359236, 6172.105044768528, 6208.81373558794, 4226.873193804191, 4460.531644177363, 6118.663473508956, 6506.3818937200895, 6847.486438517106, 6733.461323094165, 6794.898076528856, 6810.659467026654, 6815.659217039153, 6822.454203663719, 6809.625470599115, 6812.216595091553, 6825.044493770876, 6836.384725374715, 6840.418564422005, 5743.1385292206505, 5817.796377126822, 5881.500072493843, 5890.440408161217, 5895.528357731371, 27118.864405677847, 27212.31969200766, 27302.0, 27560.862195688893, 27579.0, 2494.8128890333296, 2565.1278565287707, 2600.2719238613217, 2613.7908967282574, 3059.602251707286, 15577.376904923789, 15901.0, 15946.760798588031, 16128.435504757277, 16541.0, 1875.456117725858, 1895.772507299119, 1912.2446633579798, 1939.5151212196952, 1958.8237058664788, 75824.24175758281, 76029.61985190093, 76319.0, 94599.0, 116818.83181168165, 9040.547972601344, 10741.51663175159, 10979.28634638746, 11099.056580190612, 11330.660080197607, 13679.974001949695, 13761.449542018407, 13770.65573360654, 13795.793063104062, 13802.93098534521, 8503.489790612583, 8706.303495720327, 8708.52103134321, 8748.387612867165, 8824.294056475466, 8817.867731984026, 8827.0, 8834.955825220834, 8944.329175311877, 8953.865692014626, 6584.572002819801, 6723.310675729747, 6732.528722989344, 6743.629100399525, 6744.76393326241, 1529.8087739032621, 1679.1184628070362, 1741.2773698915062, 1801.1174524482908, 1835.9173837177093, 946.4888959961681, 994.8656931314325, 1011.549860312154, 1023.6468418395641, 1030.1501261459325, 18088.63822723538, 22588.54822903533, 22729.431764205696, 22970.0, 22987.88506057459, 815.3355015662216, 951.6431338248157, 1009.8081364540714, 1027.773889744255, 1030.8917913243292, 11936.164468487123, 12023.939880300542, 12144.453499592539, 12219.083568732382, 12262.26426414418, 612114.0, 619631.0, 626601.0, 634147.8292608508, 635171.0, 69540.60918781596, 119100.61798763977, 126238.0, 128780.35609821891, 132715.33642331726, 938.863864739609, 975.4196253229317, 987.4865070163619, 987.827130252203, 1021.7956408718286, 18890.52773680614, 19838.702419464007, 19845.801541984973, 19879.503012424804, 19941.401757947286, 43584.0, 51738.74130629473, 55912.720436397554, 57853.710731447754, 58243.0, 89219.10780892108, 89456.552717236, 90096.0, 90327.0, 91330.0, 7163.64181790898, 7541.849163016818, 7553.509021913558, 7817.491863028885, 8436.029856566329, 7342.632868356454, 9434.48110353937, 13386.19682819034, 13582.25297608641, 13710.862891370958, 10189.796204076025, 11762.58830940913, 11879.426420827871, 11954.462049207632, 11973.042156627274, 11636.825447618123, 11709.47776789012, 11811.763764724828, 11848.466818992994, 11987.940060299643, 11832.290062596272, 11955.342456164994, 11980.0, 12034.977026952807, 12061.51753929868, 8340.749777506684, 8453.577321133796, 8512.97844258693, 8552.786180345542, 8688.695895643623, 10431.582736690447, 11187.440627968406, 11419.771604568028, 11776.941115294369, 11880.0], "value": 9997.881868647424, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1534.14088110658, 1586.8730501559876, 1806.9638607227855, 1936.622358640064, 1959.549303660159, 8683.869741953868, 8974.282057435406, 9168.908310916897, 9179.036201198878, 9182.219511341536, 7928.207179282069, 8164.387670924675, 8185.508869467836, 8261.710840120582, 8967.730968070951, 2144.2602302205714, 2319.69843920291, 2393.0547433763654, 2403.471236328005, 2404.350825277174, 2386.427257458206, 2389.283215035491, 2410.8915098820594, 2411.8914648840846, 2415.722191947922, 1968.9803101969012, 2326.1858349577597, 2397.304781613339, 2401.2196036288187, 2409.903603855844, 2012.4566367080952, 2321.9767802322012, 2397.8441401309033, 2403.483251101004, 2406.7713567211085, 2356.8703721295324, 2370.7510711375403, 2374.5132247889314, 2383.97616023841, 2408.1330720940446, 9087.7273681789, 9123.133302336268, 9130.726078217664, 9136.08639136081, 9147.908520914772, 5279.5512381447625, 5496.972515137399, 5500.339959204881, 5506.063969125258, 5510.6969116698565, 3576.7138628909884, 3645.125169959217, 3725.9441108383394, 3731.4514476492222, 3736.8610367291603, 1069.8127827630133, 1096.5668560918473, 1116.391566596207, 1119.0823524709742, 1121.6074373969127, 12204.755904881853, 12205.694857628541, 12242.143049986504, 12248.326342051183, 12268.26390416569, 4706.3646407735205, 4707.357657896628, 4771.165046116916, 4881.8535443936735, 4890.633202509826, 10183.847242291371, 10270.178385729123, 10277.588896444207, 10305.330153539995, 10313.174946004301, 5461.208124821878, 5469.453054694499, 5485.259489968883, 5486.149646804752, 5498.257735205776, 4460.420145381079, 4927.28554359623, 4978.103941290602, 4987.875303117378, 5003.649744517848, 3415.863365465403, 3740.3454395481226, 3979.1245925896255, 4059.573744756817, 4064.065264989069, 1249.650097972569, 1278.9872101278868, 1283.6854970532092, 1290.8128321393344, 1295.391166151907, 796.7569891183128, 888.5157589113949, 927.1146055516965, 955.0878910640322, 978.6574698855344, 6081.236441431935, 6187.535934804908, 6201.56589038772, 6238.875222495525, 6243.937560624427, 851.923326900582, 864.8659457784054, 942.528735632184, 954.5656726189567, 1012.8227560177087, 4078.4494093297026, 4093.570175131628, 4095.385692146202, 4149.792510374469, 4151.626353628188, 10430.217733669853, 10783.892161078445, 10870.293430927122, 10881.727956801144, 10949.671509854717, 10459.424731639832, 10854.403007834644, 10855.0, 10902.618408355827, 10959.56161753521, 8306.54314012735, 8336.416450848503, 8403.70587029451, 8404.873926891101, 8410.990681118305, 5955.612885162451, 5978.372270911492, 5994.490468310242, 6132.78535251264, 6244.46922011616, 8176.223258790405, 9758.853617195606, 10142.594296228068, 10165.644202452879, 10198.541065651914, 10355.171586272929, 10370.688879333633, 10374.740631484274, 10405.115565177044, 10406.739831504274, 7590.39276857839, 7644.006279183671, 7722.227777222233, 7730.574818384818, 7756.224377562198, 8655.307575393828, 8686.739397818075, 8780.034196238536, 8780.121987801169, 8795.120487951153, 7686.884696729442, 7802.804929876798, 7910.841783164189, 7952.483088599222, 7954.164812694584, 7469.626518673936, 7581.6209189541405, 7581.848363032819, 7738.342240909361, 7772.106207786177, 7192.42460603161, 7246.565206087652, 7246.963765181351, 7252.238514956065, 7272.745453909088, 5008.84973450797, 5138.8972220556125, 5154.020736060136, 5218.608604354613, 5220.216967454912, 5212.348456442945, 5258.842234732964, 5271.103912334989, 5301.655392399482, 5387.272718182995], "value": 4696.611155605322, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.58135354564246, 313.0826677833947, 314.1564898248203, 319.2672815887539, 322.33759623972725, 5309.230161626562, 5347.973260133699, 5351.250824884514, 5355.705436201008, 5377.462253774621, 4537.568930951555, 4619.838305659303, 4691.437027556698, 4877.902441951159, 5318.813841515548, 3480.2343484433386, 3841.9423708644395, 4029.5970402959615, 4063.817128229222, 4063.9390409143884, 3920.333543297632, 4030.6573941214883, 4036.5670187083456, 4066.5323487798837, 4079.8376860264107, 3979.084810493588, 3983.342748446503, 4000.599940006005, 4005.7596544207295, 4016.49793775778, 3619.873304434332, 3687.557493100818, 3789.6210378962023, 4017.9754162688473, 4058.127502586958, 2979.553067039961, 3310.0, 3513.139280876176, 3528.3648943190215, 3528.6118526962014, 4656.720596764205, 5193.584513238932, 5204.843854684365, 5224.346956630421, 5241.42344342122, 4474.507804141542, 4637.333533218379, 4654.0, 4665.580097791181, 4688.436788041079, 5470.0, 5472.20653005312, 5509.256250406184, 5522.22688823565, 5534.944650553483, 3890.649841514249, 3903.4340020697123, 3912.4131380292897, 3932.154586763831, 3933.2723446162267, 5619.634723742944, 5761.481466667978, 5862.677552734598, 5865.941340586583, 5868.2664666916635, 3465.7054150396993, 3911.78485183312, 3929.390944403622, 3937.5078115235597, 3957.08986933007, 3532.9293414131575, 4038.697097717683, 4041.939370909439, 4050.3519436890547, 4064.695147863922, 4460.1525710114975, 4633.4208223972, 4641.327007583882, 4643.605293550018, 4654.627629789608, 5199.376074871039, 5269.947300526946, 5554.750036248379, 5626.352969408488, 5662.886742265214, 1193.26614132308, 1346.4546858522315, 1361.7889227169806, 1373.9106958047696, 1381.9101758385873, 729.744589393708, 795.2921899509441, 799.7121036426881, 848.2662496940102, 849.9787505312293, 4641.814327426932, 5024.62315326352, 5193.338696219156, 5203.32356793622, 5211.609129315317, 5296.92054619181, 5724.685142317129, 5736.741846616912, 5744.425557444222, 5772.365039845614, 4696.591022693178, 4818.494058123917, 5190.273361729361, 5221.242919776687, 5225.634205605571, 2328.2316835444262, 2889.4076714273524, 2983.0, 2983.1212879571317, 2987.4323878463024, 5901.881962360729, 5935.910961335583, 5948.226730525004, 5962.25471816023, 5970.641761494324, 5133.0, 5465.262189604432, 5556.6104676147515, 5875.059990401515, 5901.878643057806, 5385.695090336016, 5686.317641882918, 5856.882862342814, 5869.530437564899, 5902.881942361215, 4853.538913803183, 4860.76504475055, 4963.4540200577185, 5721.599488035798, 5722.885542289214, 4593.058423023205, 5600.327960644749, 5600.495955363874, 5608.0, 5660.15097735343, 5853.560982926383, 5857.121431785266, 5910.079224252221, 5935.970320148372, 5993.101034844808, 3782.451544525973, 3916.2167566486246, 3937.645611894943, 3943.9688560391996, 3948.04571736992, 5322.8935421292135, 5614.6631202128, 5659.886802264014, 5676.155249543832, 5715.228444160151, 5473.616846820684, 5509.752061157177, 5530.253415788817, 5559.916601250903, 5611.943880561143, 5271.4464981176425, 5511.641743286674, 5521.613487055867, 5542.9168562472405, 5574.411292781538, 5103.3159057511675, 5201.42784293735, 5355.3305836770405, 5388.703621300866, 5394.541463975606, 4874.366332376911, 5086.745662716776, 5093.643444958817, 5140.46025167367, 5432.511074003359, 4369.956300437082, 4960.826371076995, 4964.578010868972, 5154.098032844162, 5305.336832895888], "value": 4074.433352298477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.7012849410506, 752.7177308509309, 804.987925181122, 817.525835015691, 849.2017503546664, 13284.269365184911, 15891.84108158918, 16438.260278287475, 16536.66926661466, 16539.58651033725, 14544.981851270415, 14708.779368309484, 14716.484923027696, 14717.779233311509, 14908.18005009724, 721.084223036743, 740.2079774641136, 884.2660591708882, 901.4095767272447, 902.9097090290984, 890.5680744838749, 895.7491902267343, 896.6278994217392, 899.9595018224163, 909.849874770662, 950.2635457520443, 950.3062764182151, 950.4154944709023, 950.9714708558754, 951.7430293820664, 754.7999780058261, 775.8099265679888, 777.9688812447496, 778.2101167315179, 946.3990366117496, 692.4737199728193, 709.4466316273315, 712.3161764705889, 714.4820005496026, 714.746265075901, 15197.620059498491, 15223.771643425358, 15252.38990440381, 15271.618209544738, 15295.08229506222, 13912.234827084507, 13935.790963135561, 13939.581812545639, 13984.160950343012, 14280.285985700673, 12776.0, 12969.935150324281, 12990.740185196242, 13266.204027758366, 13279.336033198302, 4467.128909862578, 4571.72569645819, 4576.679632425731, 4638.791254393526, 4690.366800481926, 112204.87795122026, 112894.48422063107, 113139.43430282878, 113270.86729132685, 113397.0, 11018.283811552223, 11047.944760276147, 11265.774684506265, 11557.942210289062, 11562.421878906021, 21025.474363141046, 21934.122635094736, 21959.67060494094, 21962.121515139534, 22000.33998980033, 6217.937820621736, 7018.0, 8024.598770061473, 8120.512769233865, 8163.428560000743, 4143.399207114951, 4547.272436410159, 4696.81212751493, 4770.0, 4807.18277892759, 3568.839402226906, 3697.9445308320396, 3730.3658378075793, 3733.9813300933865, 3739.700823934133, 661.3716968879584, 664.19963943449, 676.0467740485891, 683.7470136049693, 700.7757517594321, 619.891518984176, 623.9968800155971, 676.4993904510725, 707.3669066185838, 713.4969846258375, 8040.0, 9654.71035868925, 9743.31796774219, 9749.707508774749, 9945.154661853823, 497.56711660855285, 666.6366830077619, 684.5824047331124, 691.408845437154, 697.3479796390391, 4674.182018146811, 4789.70273732907, 4791.616670666338, 4817.8313759018265, 4826.58973987215, 97619.04761904723, 118103.0, 125526.0, 153381.2330938338, 160380.0, 133087.0, 134376.0, 135045.64954350528, 135609.0, 135887.0, 55001.72499137479, 73288.63355683081, 81987.3602527958, 97259.54110688345, 111587.97854096585, 9885.406875587487, 10043.497825108861, 10193.898061019296, 10217.948910255402, 10229.641962531276, 53125.7343713279, 61844.69077654583, 62657.12028639148, 63070.684646576476, 64516.0, 92716.0, 93057.06942930484, 93833.65415864658, 97278.0, 99436.0, 13438.529651462151, 13656.684732385844, 13690.315484225948, 13699.931500342434, 14075.936234312783, 15151.0, 15182.164980926154, 15230.543083707507, 15486.225688715744, 15772.369105235663, 14039.719205616035, 14049.648758780704, 14345.0, 14642.780358294847, 14753.483628072965, 14105.506307279196, 14270.354900882438, 14355.784663229852, 14365.569032929028, 14657.780133298222, 13508.324583770576, 13660.521881734488, 14187.503437379642, 14421.92789036048, 14424.0, 10943.398113103856, 11213.887861121611, 11473.254238474472, 11520.827187592025, 11860.110491713329, 10699.255260658707, 10910.509027093958, 11416.793619499982, 11780.234284771463, 11782.17524773292], "value": 7985.4431899365945, "unit": "score"}], "name": "dromaeo_css", "value": 7508.591813694986, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 112.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 132.0, 131.0, 155.0, 106.0, 127.0, 123.0, 108.0, 156.0, 107.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 188.0, 185.0, 183.0, 183.0, 187.0, 184.0, 181.0, 184.0, 185.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 56.0, 88.0, 76.0, 68.0, 61.0, 66.0, 82.0, 57.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 92.0, 93.0, 71.0, 71.0, 67.0, 97.0, 70.0, 68.0, 102.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 84.0, 85.0, 89.0, 87.0, 84.0, 86.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 54.0, 52.0, 52.0, 53.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 70.0, 70.0, 74.0, 73.0, 70.0, 74.0, 80.0, 103.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 86.0, 85.0, 84.0, 83.0, 86.0, 83.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 152.0, 156.0, 149.0, 133.0, 133.0, 136.0, 217.0, 135.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 170.0, 170.0, 167.0, 169.0, 185.0, 152.0, 216.0, 221.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 69.0, 80.0, 87.0, 85.0, 85.0, 70.0, 65.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1459.6999999999998, "alertThreshold": 2.0}]} 23:13:05 INFO - Return code: 0 23:13:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:13:05 INFO - # TBPL SUCCESS # 23:13:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:13:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:13:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:13:05 INFO - 2016-07-19 23:13:05.566 screenresolution[1928:17813] starting screenresolution argv=screenresolution get 23:13:05 INFO - 2016-07-19 23:13:05.569 screenresolution[1928:17813] Display 0: 1600x1200x32@60 23:13:05 INFO - 2016-07-19 23:13:05.575 screenresolution[1929:17816] starting screenresolution argv=screenresolution list 23:13:05 INFO - Available Modes on Display 0 23:13:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:13:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:13:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:13:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:13:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:13:05 INFO - Intel Iris: 23:13:05 INFO - Chipset Model: Intel Iris 23:13:05 INFO - Type: GPU 23:13:05 INFO - Bus: Built-In 23:13:05 INFO - VRAM (Dynamic, Max): 1536 MB 23:13:05 INFO - Vendor: Intel (0x8086) 23:13:05 INFO - Device ID: 0x0a2e 23:13:05 INFO - Revision ID: 0x0009 23:13:05 INFO - Displays: 23:13:05 INFO - Display: 23:13:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:13:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:13:05 INFO - Display Serial Number: 1600x1200 60 23:13:05 INFO - Main Display: Yes 23:13:05 INFO - Mirror: Off 23:13:05 INFO - Online: Yes 23:13:05 INFO - Rotation: Supported 23:13:05 INFO - Return code: 0 23:13:05 INFO - Running post-action listener: _resource_record_post_action 23:13:05 INFO - [mozharness: 2016-07-20 06:13:05.810942Z] Finished run-tests step (success) 23:13:05 INFO - Running post-run listener: _resource_record_post_run 23:13:05 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 1921024; Write bytes: 702645248; Read time: 15; Write time: 1941 23:13:05 INFO - TinderboxPrint: CPU usage
53.6% 23:13:05 INFO - TinderboxPrint: I/O read bytes / time
1,921,024 / 15 23:13:05 INFO - TinderboxPrint: I/O write bytes / time
702,645,248 / 1,941 23:13:05 INFO - TinderboxPrint: CPU idle
2,246.4 (46.4%) 23:13:05 INFO - TinderboxPrint: CPU system
968.1 (20.0%) 23:13:05 INFO - TinderboxPrint: CPU user
1,627.1 (33.6%) 23:13:05 INFO - TinderboxPrint: Swap in / out
409,755,648 / 0 23:13:05 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388735488; Write bytes: 383264768; Read time: 29004; Write time: 700 23:13:05 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1921024; Write bytes: 292097024; Read time: 15; Write time: 1192 23:13:06 INFO - Running post-run listener: _upload_blobber_files 23:13:06 INFO - Blob upload gear active. 23:13:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:13:06 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 23:13:06 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:13:06 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:13:06 INFO - (blobuploader) - INFO - Open directory for files ... 23:13:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:13:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:13:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:07 INFO - (blobuploader) - INFO - Done attempting. 23:13:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:13:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:13:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:08 INFO - (blobuploader) - INFO - Done attempting. 23:13:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:13:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:13:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:13 INFO - (blobuploader) - INFO - Done attempting. 23:13:13 INFO - (blobuploader) - INFO - Iteration through files over. 23:13:13 INFO - Return code: 0 23:13:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:13:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:13:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5bbb94894db48a542610b6b0b240d8a0bd5ddfa7e0b2c6f06f045452ed90e9551cfb09a6960f73448d61da5c5938ba082837896b9a0d1cd1639012bdae0ade5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3c8ae127936c35087ec1f2b307e2e033f5686f4270622c8d623f3772a87ca2b26a1a6f7e6136a9bdfef24d63953c60016604dfaa634921faa004deb0a369941", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/44cf9592f094fbe74ba1482cfb2c5c5d29bd779991bcc4024c9989d8efdf04a15e1744fad22c36d0d18e7b168634c79eea584165fa646432161bad91ab6fa167"} 23:13:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:13:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:13:13 INFO - Contents: 23:13:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5bbb94894db48a542610b6b0b240d8a0bd5ddfa7e0b2c6f06f045452ed90e9551cfb09a6960f73448d61da5c5938ba082837896b9a0d1cd1639012bdae0ade5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3c8ae127936c35087ec1f2b307e2e033f5686f4270622c8d623f3772a87ca2b26a1a6f7e6136a9bdfef24d63953c60016604dfaa634921faa004deb0a369941", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/44cf9592f094fbe74ba1482cfb2c5c5d29bd779991bcc4024c9989d8efdf04a15e1744fad22c36d0d18e7b168634c79eea584165fa646432161bad91ab6fa167"} 23:13:13 INFO - Running post-run listener: copy_logs_to_upload_dir 23:13:13 INFO - Copying logs to upload dir... 23:13:13 INFO - mkdir: /builds/slave/test/build/upload/logs 23:13:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.946792 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-19 23:13:13.090473) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 23:13:13.094459) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dhFGlieITd/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5bbb94894db48a542610b6b0b240d8a0bd5ddfa7e0b2c6f06f045452ed90e9551cfb09a6960f73448d61da5c5938ba082837896b9a0d1cd1639012bdae0ade5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3c8ae127936c35087ec1f2b307e2e033f5686f4270622c8d623f3772a87ca2b26a1a6f7e6136a9bdfef24d63953c60016604dfaa634921faa004deb0a369941", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/44cf9592f094fbe74ba1482cfb2c5c5d29bd779991bcc4024c9989d8efdf04a15e1744fad22c36d0d18e7b168634c79eea584165fa646432161bad91ab6fa167"} build_url:https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011137 build_url: 'https://queue.taskcluster.net/v1/task/N9nICup1TeqDVDmY4AEAdQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5bbb94894db48a542610b6b0b240d8a0bd5ddfa7e0b2c6f06f045452ed90e9551cfb09a6960f73448d61da5c5938ba082837896b9a0d1cd1639012bdae0ade5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e3c8ae127936c35087ec1f2b307e2e033f5686f4270622c8d623f3772a87ca2b26a1a6f7e6136a9bdfef24d63953c60016604dfaa634921faa004deb0a369941", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/44cf9592f094fbe74ba1482cfb2c5c5d29bd779991bcc4024c9989d8efdf04a15e1744fad22c36d0d18e7b168634c79eea584165fa646432161bad91ab6fa167"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 23:13:13.128043) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:13:13.128530) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dhFGlieITd/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KjzHEplXoC/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 23:13:13.177956) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 23:13:13.178260) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 23:13:13.178572) ========= ========= Total master_lag: 0.18 =========