builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1468957564.26 results: success (0) buildid: 20160719105020 builduid: 86745b93156d43228f165373165b4a2b revision: d1eef136f2523f1726a16c48eace3df02766ac01 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.265423) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.266400) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.266894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.293223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.293928) ========= 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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-07-19 12:46:04 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137428 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.459802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.460656) ========= 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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.027556 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:04.514504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 12:46:04.515313) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d1eef136f2523f1726a16c48eace3df02766ac01 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d1eef136f2523f1726a16c48eace3df02766ac01 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qz1x8ZB49F/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.7vtRJlGaIr/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:04,591 truncating revision to first 12 chars 2016-07-19 12:46:04,591 Setting DEBUG logging. 2016-07-19 12:46:04,591 attempt 1/10 2016-07-19 12:46:04,591 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d1eef136f252?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 12:46:05,300 unpacking tar archive at: fx-team-d1eef136f252/testing/mozharness/ program finished with exit code 0 elapsedTime=1.000688 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 12:46:05.539295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:05.539674) ========= script_repo_revision: d1eef136f2523f1726a16c48eace3df02766ac01 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:05.540119) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:05.540427) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 12:46:05.559248) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-19 12:46:05.559553) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qz1x8ZB49F/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.7vtRJlGaIr/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:05 INFO - MultiFileLogger online at 20160719 12:46:05 in /builds/slave/test 12:46:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:46:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:46:05 INFO - {'append_to_log': False, 12:46:05 INFO - 'base_work_dir': '/builds/slave/test', 12:46:05 INFO - 'blob_upload_branch': 'Fx-Team', 12:46:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:46:05 INFO - 'branch': 'Fx-Team', 12:46:05 INFO - 'buildbot_json_path': 'buildprops.json', 12:46:05 INFO - 'config_files': ('talos/mac_config.py',), 12:46:05 INFO - 'default_actions': ('clobber', 12:46:05 INFO - 'read-buildbot-config', 12:46:05 INFO - 'download-and-extract', 12:46:05 INFO - 'populate-webroot', 12:46:05 INFO - 'create-virtualenv', 12:46:05 INFO - 'install', 12:46:05 INFO - 'run-tests'), 12:46:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:46:05 INFO - 'download_minidump_stackwalk': True, 12:46:05 INFO - 'download_symbols': 'ondemand', 12:46:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:46:05 INFO - 'tooltool.py': '/tools/tooltool.py', 12:46:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:46:05 INFO - '/tools/misc-python/virtualenv.py')}, 12:46:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:46:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:46:05 INFO - 'installer_path': 'installer.exe', 12:46:05 INFO - 'log_level': 'info', 12:46:05 INFO - 'log_name': 'talos', 12:46:05 INFO - 'log_to_console': True, 12:46:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:46:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:46:05 INFO - 'opt_config_files': (), 12:46:05 INFO - 'pip_index': False, 12:46:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:46:05 INFO - 'cmd': ('bash', 12:46:05 INFO - '-c', 12:46:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:46:05 INFO - 'enabled': True, 12:46:05 INFO - 'halt_on_failure': False, 12:46:05 INFO - 'name': 'check_screen_resolution'},), 12:46:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:46:05 INFO - 'cmd': ('bash', 12:46:05 INFO - '-c', 12:46:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:46:05 INFO - 'enabled': True, 12:46:05 INFO - 'halt_on_failure': False, 12:46:05 INFO - 'name': 'check_screen_resolution'},), 12:46:05 INFO - 'run_cmd_checks_enabled': True, 12:46:05 INFO - 'sps_profile': False, 12:46:05 INFO - 'sps_profile_interval': 0, 12:46:05 INFO - 'suite': 'dromaeojs', 12:46:05 INFO - 'system_bits': '32', 12:46:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:46:05 INFO - 'title': 't-yosemite-r7-0034', 12:46:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:46:05 INFO - 'use_talos_json': True, 12:46:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:46:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:46:05 INFO - 'work_dir': 'build'} 12:46:05 INFO - ##### 12:46:05 INFO - ##### Running clobber step. 12:46:05 INFO - ##### 12:46:05 INFO - Running pre-action listener: _resource_record_pre_action 12:46:05 INFO - Running main action method: clobber 12:46:05 INFO - rmtree: /builds/slave/test/build 12:46:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:46:07 INFO - Running post-action listener: _resource_record_post_action 12:46:07 INFO - ##### 12:46:07 INFO - ##### Finished clobber step (success) 12:46:07 INFO - ##### 12:46:07 INFO - ##### 12:46:07 INFO - ##### Running read-buildbot-config step. 12:46:07 INFO - ##### 12:46:07 INFO - Running pre-action listener: _resource_record_pre_action 12:46:07 INFO - Running main action method: read_buildbot_config 12:46:07 INFO - Using buildbot properties: 12:46:07 INFO - { 12:46:07 INFO - "project": "", 12:46:07 INFO - "product": "firefox", 12:46:07 INFO - "script_repo_revision": "production", 12:46:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:46:07 INFO - "repository": "", 12:46:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:46:07 INFO - "buildid": "20160719105020", 12:46:07 INFO - "pgo_build": "False", 12:46:07 INFO - "basedir": "/builds/slave/test", 12:46:07 INFO - "buildnumber": 97, 12:46:07 INFO - "slavename": "t-yosemite-r7-0034", 12:46:07 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:46:07 INFO - "platform": "yosemite_r7", 12:46:07 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 12:46:07 INFO - "branch": "fx-team", 12:46:07 INFO - "repo_path": "integration/fx-team", 12:46:07 INFO - "revision": "d1eef136f2523f1726a16c48eace3df02766ac01", 12:46:07 INFO - "stage_platform": "macosx64", 12:46:07 INFO - "builduid": "86745b93156d43228f165373165b4a2b", 12:46:07 INFO - "slavebuilddir": "test" 12:46:07 INFO - } 12:46:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 12:46:07 INFO - Running post-action listener: _resource_record_post_action 12:46:07 INFO - ##### 12:46:07 INFO - ##### Finished read-buildbot-config step (success) 12:46:07 INFO - ##### 12:46:07 INFO - ##### 12:46:07 INFO - ##### Running download-and-extract step. 12:46:07 INFO - ##### 12:46:07 INFO - Running pre-action listener: _resource_record_pre_action 12:46:07 INFO - Running main action method: download_and_extract 12:46:07 INFO - mkdir: /builds/slave/test/build/tests 12:46: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')]} 12:46:07 INFO - https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:46:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:46:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 12:46:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:09 INFO - Downloaded 1199 bytes. 12:46:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 12:46:09 INFO - Using the following test package requirements: 12:46:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:46:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 12:46:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 12:46:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'jsshell-mac.zip'], 12:46:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 12:46:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 12:46:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 12:46:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 12:46:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 12:46:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 12:46:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 12:46:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:46:09 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')]} 12:46:09 INFO - https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:11 INFO - Downloaded 18835350 bytes. 12:46:11 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'] 12:46:11 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 12:46:14 INFO - Return code: 0 12:46: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 12:46: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')]} 12:46:14 INFO - https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 12:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:15 INFO - Downloaded 18835350 bytes. 12:46: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'] 12:46: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 12:46:19 INFO - Return code: 0 12:46:19 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')]} 12:46:19 INFO - https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:46:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:46:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 12:46:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:21 INFO - Downloaded 14403317 bytes. 12:46:21 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'] 12:46:21 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 12:46:21 INFO - Return code: 0 12:46:21 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')]} 12:46:21 INFO - https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:46:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:46:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:46:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 12:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Stn90HOlSzy0wRzU0E9sZA/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 12:46:24 INFO - Downloaded 112873033 bytes. 12:46:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:46:24 INFO - mkdir: /builds/slave/test/properties 12:46:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:46:24 INFO - Writing to file /builds/slave/test/properties/build_url 12:46:24 INFO - Contents: 12:46:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 12:46:25 INFO - Running post-action listener: _resource_record_post_action 12:46:25 INFO - Running post-action listener: set_extra_try_arguments 12:46:25 INFO - ##### 12:46:25 INFO - ##### Finished download-and-extract step (success) 12:46:25 INFO - ##### 12:46:25 INFO - ##### 12:46:25 INFO - ##### Running populate-webroot step. 12:46:25 INFO - ##### 12:46:25 INFO - Running pre-action listener: _resource_record_pre_action 12:46:25 INFO - Running main action method: populate_webroot 12:46:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:46:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:46:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:46:25 INFO - '--tppagecycles', 12:46:25 INFO - '7'], 12:46:25 INFO - 'tests': ['tsvgm']}}, 12:46:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:46:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:46:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:46:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:46:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:46:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:46:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['damp', 'tps']}, 12:46:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'tests': ['damp', 'tps']}, 12:46:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['dromaeo_dom']}, 12:46:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:46:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['basic_compositor_video']}, 12:46:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:46:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['a11yr', 12:46:25 INFO - 'ts_paint', 12:46:25 INFO - 'tpaint', 12:46:25 INFO - 'sessionrestore', 12:46:25 INFO - 'sessionrestore_no_auto_restore', 12:46:25 INFO - 'tabpaint']}, 12:46:25 INFO - 'other-e10s': {'tests': ['a11yr', 12:46:25 INFO - 'ts_paint', 12:46:25 INFO - 'tpaint', 12:46:25 INFO - 'sessionrestore', 12:46:25 INFO - 'sessionrestore_no_auto_restore', 12:46:25 INFO - 'tabpaint']}, 12:46:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:46:25 INFO - 'ts_paint', 12:46:25 INFO - 'tpaint', 12:46:25 INFO - 'sessionrestore', 12:46:25 INFO - 'sessionrestore_no_auto_restore', 12:46:25 INFO - 'tabpaint']}, 12:46:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:46:25 INFO - 'ts_paint', 12:46:25 INFO - 'tpaint', 12:46:25 INFO - 'sessionrestore', 12:46:25 INFO - 'sessionrestore_no_auto_restore', 12:46:25 INFO - 'tabpaint']}, 12:46:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['a11yr', 12:46:25 INFO - 'ts_paint', 12:46:25 INFO - 'tpaint', 12:46:25 INFO - 'sessionrestore', 12:46:25 INFO - 'sessionrestore_no_auto_restore', 12:46:25 INFO - 'tabpaint']}, 12:46:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['a11yr', 12:46:25 INFO - 'ts_paint', 12:46:25 INFO - 'tpaint', 12:46:25 INFO - 'sessionrestore', 12:46:25 INFO - 'sessionrestore_no_auto_restore', 12:46:25 INFO - 'tabpaint']}, 12:46:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['tsvgx', 12:46:25 INFO - 'tsvgr_opacity', 12:46:25 INFO - 'tart', 12:46:25 INFO - 'tscrollx', 12:46:25 INFO - 'cart']}, 12:46:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:46:25 INFO - 'tsvgr_opacity', 12:46:25 INFO - 'tart', 12:46:25 INFO - 'tscrollx', 12:46:25 INFO - 'cart']}, 12:46:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'talos_options': ['--disable-e10s'], 12:46:25 INFO - 'tests': ['tp5o']}, 12:46:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'tests': ['tp5o']}, 12:46:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'talos_options': ['--disable-e10s', 12:46:25 INFO - '--xperf_path', 12:46:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:46:25 INFO - 'tests': ['tp5n']}, 12:46:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:46:25 INFO - 'talos_options': ['--xperf_path', 12:46:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:46:25 INFO - 'tests': ['tp5n']}}, 12:46:25 INFO - 'talos.zip': {'path': '', 12:46:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:46:25 INFO - Running post-action listener: _resource_record_post_action 12:46:25 INFO - ##### 12:46:25 INFO - ##### Finished populate-webroot step (success) 12:46:25 INFO - ##### 12:46:25 INFO - ##### 12:46:25 INFO - ##### Running create-virtualenv step. 12:46:25 INFO - ##### 12:46:25 INFO - Running pre-action listener: _resource_record_pre_action 12:46:25 INFO - Running main action method: create_virtualenv 12:46:25 INFO - Creating virtualenv /builds/slave/test/build/venv 12:46:25 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 12:46:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:46:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:46:25 INFO - Using real prefix '/tools/python27' 12:46:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:46:26 INFO - Installing distribute.............................................................................................................................................................................................done. 12:46:29 INFO - Installing pip.................done. 12:46:29 INFO - Return code: 0 12:46:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:46: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')]} 12:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46: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')]} 12:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:29 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:29 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 12:46:29 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 12:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:29 INFO - 'HOME': '/Users/cltbld', 12:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:29 INFO - 'LOGNAME': 'cltbld', 12:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:29 INFO - 'MOZ_NO_REMOTE': '1', 12:46:29 INFO - 'NO_EM_RESTART': '1', 12:46:29 INFO - 'PAGER': '/bin/cat', 12:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:29 INFO - 'PWD': '/builds/slave/test', 12:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:29 INFO - 'SHELL': '/bin/bash', 12:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:29 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:29 INFO - 'USER': 'cltbld', 12:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:29 INFO - 'XPC_FLAGS': '0x0', 12:46:29 INFO - 'XPC_SERVICE_NAME': '0', 12:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:29 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 12:46:29 INFO - Cleaning up... 12:46:29 INFO - Return code: 0 12:46:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:46: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')]} 12:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46: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')]} 12:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:29 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:29 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 12:46:29 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 12:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:29 INFO - 'HOME': '/Users/cltbld', 12:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:29 INFO - 'LOGNAME': 'cltbld', 12:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:29 INFO - 'MOZ_NO_REMOTE': '1', 12:46:29 INFO - 'NO_EM_RESTART': '1', 12:46:29 INFO - 'PAGER': '/bin/cat', 12:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:29 INFO - 'PWD': '/builds/slave/test', 12:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:29 INFO - 'SHELL': '/bin/bash', 12:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:29 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:29 INFO - 'USER': 'cltbld', 12:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:29 INFO - 'XPC_FLAGS': '0x0', 12:46:29 INFO - 'XPC_SERVICE_NAME': '0', 12:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:30 INFO - Downloading/unpacking psutil>=3.1.1 12:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:46:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:46:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:46:32 INFO - Installing collected packages: psutil 12:46:32 INFO - Running setup.py install for psutil 12:46:33 INFO - building 'psutil._psutil_osx' extension 12:46:33 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 12:46:33 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 12:46:33 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 12:46:33 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 12:46:33 INFO - building 'psutil._psutil_posix' extension 12:46:33 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 12:46:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:46:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:46:33 INFO - ^ 12:46:33 INFO - 1 warning generated. 12:46:33 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 12:46:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:46:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:46:34 INFO - Successfully installed psutil 12:46:34 INFO - Cleaning up... 12:46:34 INFO - Return code: 0 12:46:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:46:34 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')]} 12:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:34 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')]} 12:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:34 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:34 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 12:46:34 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 12:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:34 INFO - 'HOME': '/Users/cltbld', 12:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:34 INFO - 'LOGNAME': 'cltbld', 12:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:34 INFO - 'MOZ_NO_REMOTE': '1', 12:46:34 INFO - 'NO_EM_RESTART': '1', 12:46:34 INFO - 'PAGER': '/bin/cat', 12:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:34 INFO - 'PWD': '/builds/slave/test', 12:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:34 INFO - 'SHELL': '/bin/bash', 12:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:34 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:34 INFO - 'USER': 'cltbld', 12:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:34 INFO - 'XPC_FLAGS': '0x0', 12:46:34 INFO - 'XPC_SERVICE_NAME': '0', 12:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:46:34 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 12:46:34 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 12:46:34 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 12:46:34 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 12:46:34 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 12:46:34 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 12:46:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:46:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:46:37 INFO - Installing collected packages: mozsystemmonitor 12:46:37 INFO - Running setup.py install for mozsystemmonitor 12:46:37 INFO - Successfully installed mozsystemmonitor 12:46:37 INFO - Cleaning up... 12:46:37 INFO - Return code: 0 12:46:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:46:37 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')]} 12:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:37 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')]} 12:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:37 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:37 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 12:46:37 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 12:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:37 INFO - 'HOME': '/Users/cltbld', 12:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:37 INFO - 'LOGNAME': 'cltbld', 12:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:37 INFO - 'MOZ_NO_REMOTE': '1', 12:46:37 INFO - 'NO_EM_RESTART': '1', 12:46:37 INFO - 'PAGER': '/bin/cat', 12:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:37 INFO - 'PWD': '/builds/slave/test', 12:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:37 INFO - 'SHELL': '/bin/bash', 12:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:37 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:37 INFO - 'USER': 'cltbld', 12:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:37 INFO - 'XPC_FLAGS': '0x0', 12:46:37 INFO - 'XPC_SERVICE_NAME': '0', 12:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:37 INFO - Downloading/unpacking jsonschema==2.5.1 12:46:37 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 12:46:37 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 12:46:37 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 12:46:37 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 12:46:37 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 12:46:37 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 12:46:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:46:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 INFO - Downloading functools32-3.2.3-2.tar.gz 12:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:46:40 INFO - warning: no files found matching '*.txt' 12:46:40 INFO - no previously-included directories found matching 'build' 12:46:40 INFO - no previously-included directories found matching 'dist' 12:46:40 INFO - no previously-included directories found matching '.git*' 12:46:40 INFO - Installing collected packages: jsonschema, functools32 12:46:40 INFO - Running setup.py install for functools32 12:46:40 INFO - warning: no files found matching '*.txt' 12:46:40 INFO - no previously-included directories found matching 'build' 12:46:40 INFO - no previously-included directories found matching 'dist' 12:46:40 INFO - no previously-included directories found matching '.git*' 12:46:40 INFO - Successfully installed jsonschema functools32 12:46:40 INFO - Cleaning up... 12:46:40 INFO - Return code: 0 12:46:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:46:40 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')]} 12:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:40 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')]} 12:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:40 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:40 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 12:46:40 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 12:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:40 INFO - 'HOME': '/Users/cltbld', 12:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:40 INFO - 'LOGNAME': 'cltbld', 12:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:40 INFO - 'MOZ_NO_REMOTE': '1', 12:46:40 INFO - 'NO_EM_RESTART': '1', 12:46:40 INFO - 'PAGER': '/bin/cat', 12:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:40 INFO - 'PWD': '/builds/slave/test', 12:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:40 INFO - 'SHELL': '/bin/bash', 12:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:40 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:40 INFO - 'USER': 'cltbld', 12:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:40 INFO - 'XPC_FLAGS': '0x0', 12:46:40 INFO - 'XPC_SERVICE_NAME': '0', 12:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:46: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 12:46: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 12:46: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 12:46: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 12:46: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 12:46: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 12:46:43 INFO - Downloading blobuploader-1.2.4.tar.gz 12:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:46:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:46:43 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 12:46:43 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 12:46:43 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 12:46:43 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 12:46:43 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 12:46:43 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 12:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:46:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:46:44 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 12:46:44 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 12:46:44 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 12:46:44 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 12:46:44 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 12:46:44 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 12:46:45 INFO - Downloading docopt-0.6.1.tar.gz 12:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:46:45 INFO - Installing collected packages: blobuploader, requests, docopt 12:46:45 INFO - Running setup.py install for blobuploader 12:46:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:46:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:46:45 INFO - Running setup.py install for requests 12:46:45 INFO - Running setup.py install for docopt 12:46:45 INFO - Successfully installed blobuploader requests docopt 12:46:45 INFO - Cleaning up... 12:46:46 INFO - Return code: 0 12:46:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:46 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')]} 12:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:46 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')]} 12:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:46 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:46 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 12:46:46 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 12:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:46 INFO - 'HOME': '/Users/cltbld', 12:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:46 INFO - 'LOGNAME': 'cltbld', 12:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:46 INFO - 'MOZ_NO_REMOTE': '1', 12:46:46 INFO - 'NO_EM_RESTART': '1', 12:46:46 INFO - 'PAGER': '/bin/cat', 12:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:46 INFO - 'PWD': '/builds/slave/test', 12:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:46 INFO - 'SHELL': '/bin/bash', 12:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:46 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:46 INFO - 'USER': 'cltbld', 12:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:46 INFO - 'XPC_FLAGS': '0x0', 12:46:46 INFO - 'XPC_SERVICE_NAME': '0', 12:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:46:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Lh1Ddv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:46:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-TfDzEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:46:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bo0zky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:46:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MNyKbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:46:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-FsEI7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:46:46 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Oovjf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-q8vt3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QpR7nQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JPdqy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-gRdkY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-W96fpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-WgIdI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-BzAfFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Kywiwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:47 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-a4NdI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:48 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-CVUGXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:48 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NtQ1AJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:46:48 INFO - Running setup.py install for manifestparser 12:46:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:46:48 INFO - Running setup.py install for mozcrash 12:46:48 INFO - Running setup.py install for mozdebug 12:46:48 INFO - Running setup.py install for mozdevice 12:46:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:46:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:46:48 INFO - Running setup.py install for mozfile 12:46:49 INFO - Running setup.py install for mozhttpd 12:46:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozinfo 12:46:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozInstall 12:46:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozleak 12:46:49 INFO - Running setup.py install for mozlog 12:46:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for moznetwork 12:46:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozprocess 12:46:50 INFO - Running setup.py install for mozprofile 12:46:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Running setup.py install for mozrunner 12:46:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Running setup.py install for mozscreenshot 12:46:50 INFO - Running setup.py install for moztest 12:46:50 INFO - Running setup.py install for mozversion 12:46:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:46:50 INFO - Cleaning up... 12:46:50 INFO - Return code: 0 12:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:50 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')]} 12:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:50 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')]} 12:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:50 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:50 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 12:46:50 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 12:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:50 INFO - 'HOME': '/Users/cltbld', 12:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:50 INFO - 'LOGNAME': 'cltbld', 12:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:50 INFO - 'MOZ_NO_REMOTE': '1', 12:46:50 INFO - 'NO_EM_RESTART': '1', 12:46:50 INFO - 'PAGER': '/bin/cat', 12:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:50 INFO - 'PWD': '/builds/slave/test', 12:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:50 INFO - 'SHELL': '/bin/bash', 12:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:50 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:50 INFO - 'USER': 'cltbld', 12:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:50 INFO - 'XPC_FLAGS': '0x0', 12:46:50 INFO - 'XPC_SERVICE_NAME': '0', 12:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-KXja6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Xa0dAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-W4sxpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-rkz0Ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EvQZiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-YVuJJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:46:51 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZfsqVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-mv0LXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-o43utU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NCRAXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-B4sBuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-b4wZ9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6oox6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-KJDUCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-pnhrRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:52 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-VKO0I5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:53 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)) 12:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:53 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-AFKmBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:53 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)) 12:46:53 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)) 12:46:53 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)) 12:46:53 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)) 12:46:53 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)) 12:46:53 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)) 12:46:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:46:53 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 12:46:53 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 12:46:53 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 12:46:53 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 12:46:53 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 12:46:53 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 12:46:55 INFO - Downloading blessings-1.6.tar.gz 12:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:46:55 INFO - Installing collected packages: blessings 12:46:55 INFO - Running setup.py install for blessings 12:46:56 INFO - Successfully installed blessings 12:46:56 INFO - Cleaning up... 12:46:56 INFO - Return code: 0 12:46:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:56 INFO - Reading from file tmpfile_stdout 12:46:56 INFO - Current package versions: 12:46:56 INFO - blessings == 1.6 12:46:56 INFO - blobuploader == 1.2.4 12:46:56 INFO - docopt == 0.6.1 12:46:56 INFO - functools32 == 3.2.3-2 12:46:56 INFO - jsonschema == 2.5.1 12:46:56 INFO - manifestparser == 1.1 12:46:56 INFO - mozInstall == 1.12 12:46:56 INFO - mozcrash == 0.17 12:46:56 INFO - mozdebug == 0.1 12:46:56 INFO - mozdevice == 0.48 12:46:56 INFO - mozfile == 1.2 12:46:56 INFO - mozhttpd == 0.7 12:46:56 INFO - mozinfo == 0.9 12:46:56 INFO - mozleak == 0.1 12:46:56 INFO - mozlog == 3.2 12:46:56 INFO - moznetwork == 0.27 12:46:56 INFO - mozprocess == 0.23 12:46:56 INFO - mozprofile == 0.28 12:46:56 INFO - mozrunner == 6.12 12:46:56 INFO - mozscreenshot == 0.1 12:46:56 INFO - mozsystemmonitor == 0.3 12:46:56 INFO - moztest == 0.7 12:46:56 INFO - mozversion == 1.4 12:46:56 INFO - psutil == 3.1.1 12:46:56 INFO - requests == 1.2.3 12:46:56 INFO - wsgiref == 0.1.2 12:46:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:56 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 12:46:56 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 12:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:56 INFO - 'HOME': '/Users/cltbld', 12:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:56 INFO - 'LOGNAME': 'cltbld', 12:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:56 INFO - 'MOZ_NO_REMOTE': '1', 12:46:56 INFO - 'NO_EM_RESTART': '1', 12:46:56 INFO - 'PAGER': '/bin/cat', 12:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:56 INFO - 'PWD': '/builds/slave/test', 12:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:56 INFO - 'SHELL': '/bin/bash', 12:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:56 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:56 INFO - 'USER': 'cltbld', 12:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:56 INFO - 'XPC_FLAGS': '0x0', 12:46:56 INFO - 'XPC_SERVICE_NAME': '0', 12:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 INFO - Cleaning up... 12:46:56 INFO - Return code: 0 12:46:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 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 0x10e565a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88e9c828d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, '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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 12:46:56 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 12:46:56 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 12:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:56 INFO - 'HOME': '/Users/cltbld', 12:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:56 INFO - 'LOGNAME': 'cltbld', 12:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:56 INFO - 'MOZ_NO_REMOTE': '1', 12:46:56 INFO - 'NO_EM_RESTART': '1', 12:46:56 INFO - 'PAGER': '/bin/cat', 12:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:56 INFO - 'PWD': '/builds/slave/test', 12:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:56 INFO - 'SHELL': '/bin/bash', 12:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:46:56 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:56 INFO - 'USER': 'cltbld', 12:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:56 INFO - 'XPC_FLAGS': '0x0', 12:46:56 INFO - 'XPC_SERVICE_NAME': '0', 12:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:46:56 INFO - Cleaning up... 12:46:56 INFO - Return code: 0 12:46:56 INFO - Running post-action listener: _resource_record_post_action 12:46:56 INFO - Running post-action listener: _start_resource_monitoring 12:46:56 INFO - Starting resource monitoring. 12:46:56 INFO - ##### 12:46:56 INFO - ##### Finished create-virtualenv step (success) 12:46:56 INFO - ##### 12:46:56 INFO - ##### 12:46:56 INFO - ##### Running install step. 12:46:56 INFO - ##### 12:46:56 INFO - Running pre-action listener: _resource_record_pre_action 12:46:56 INFO - Running main action method: install 12:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:57 INFO - Reading from file tmpfile_stdout 12:46:57 INFO - Detecting whether we're running mozinstall >=1.0... 12:46:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:46:57 INFO - Reading from file tmpfile_stdout 12:46:57 INFO - Output received: 12:46:57 INFO - Usage: mozinstall [options] installer 12:46:57 INFO - Options: 12:46:57 INFO - -h, --help show this help message and exit 12:46:57 INFO - -d DEST, --destination=DEST 12:46:57 INFO - Directory to install application into. [default: 12:46:57 INFO - "/builds/slave/test"] 12:46:57 INFO - --app=APP Application being installed. [default: firefox] 12:46:57 INFO - mkdir: /builds/slave/test/build/application 12:46:57 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'] 12:46:57 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 12:47:33 INFO - Reading from file tmpfile_stdout 12:47:33 INFO - Output received: 12:47:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:47:33 INFO - Running post-action listener: _resource_record_post_action 12:47:33 INFO - ##### 12:47:33 INFO - ##### Finished install step (success) 12:47:33 INFO - ##### 12:47:33 INFO - ##### 12:47:33 INFO - ##### Running run-tests step. 12:47:33 INFO - ##### 12:47:33 INFO - Running pre-action listener: _resource_record_pre_action 12:47:33 INFO - Running main action method: run_tests 12:47:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:47:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:47:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:47:33 INFO - 2016-07-19 12:47:33.179 screenresolution[1635:7408] starting screenresolution argv=screenresolution get 12:47:33 INFO - 2016-07-19 12:47:33.182 screenresolution[1635:7408] Display 0: 1600x1200x32@60 12:47:33 INFO - 2016-07-19 12:47:33.187 screenresolution[1636:7410] starting screenresolution argv=screenresolution list 12:47:33 INFO - Available Modes on Display 0 12:47:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:47:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:47:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:47:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:47:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:47:33 INFO - Intel Iris: 12:47:33 INFO - Chipset Model: Intel Iris 12:47:33 INFO - Type: GPU 12:47:33 INFO - Bus: Built-In 12:47:33 INFO - VRAM (Dynamic, Max): 1536 MB 12:47:33 INFO - Vendor: Intel (0x8086) 12:47:33 INFO - Device ID: 0x0a2e 12:47:33 INFO - Revision ID: 0x0009 12:47:33 INFO - Displays: 12:47:33 INFO - Display: 12:47:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:47:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:47:33 INFO - Display Serial Number: 1600x1200 60 12:47:33 INFO - Main Display: Yes 12:47:33 INFO - Mirror: Off 12:47:33 INFO - Online: Yes 12:47:33 INFO - Rotation: Supported 12:47:33 INFO - Return code: 0 12:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:47:33 INFO - Python 2.7.3 12:47:33 INFO - Return code: 0 12:47:33 INFO - grabbing minidump binary from tooltool 12:47:33 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')]} 12:47:33 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 0x10e4c9d30>, '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 0x10e22edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e4c1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:47:33 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 12:47:33 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 12:47:33 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 12:47:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:47:33 INFO - Return code: 0 12:47:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:47:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:47:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:47:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:47:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 12:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 12:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qz1x8ZB49F/Render', 12:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:47:33 INFO - 'HOME': '/Users/cltbld', 12:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:47:33 INFO - 'LOGNAME': 'cltbld', 12:47:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:47:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:33 INFO - 'MOZ_NO_REMOTE': '1', 12:47:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:47:33 INFO - 'NO_EM_RESTART': '1', 12:47:33 INFO - 'PAGER': '/bin/cat', 12:47:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:33 INFO - 'PWD': '/builds/slave/test', 12:47:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:47:33 INFO - 'SHELL': '/bin/bash', 12:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vtRJlGaIr/Listeners', 12:47:33 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 12:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:47:33 INFO - 'USER': 'cltbld', 12:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:33 INFO - 'XPC_FLAGS': '0x0', 12:47:33 INFO - 'XPC_SERVICE_NAME': '0', 12:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:47:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 12:47:33 INFO - mozversion application_buildid: 20160719105020 12:47:33 INFO - mozversion application_changeset: d1eef136f2523f1726a16c48eace3df02766ac01 12:47:33 INFO - mozversion application_display_name: Nightly 12:47:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:47:33 INFO - mozversion application_name: Firefox 12:47:33 INFO - mozversion application_remotingname: firefox 12:47:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:47:33 INFO - mozversion application_vendor: Mozilla 12:47:33 INFO - mozversion application_version: 50.0a1 12:47:33 INFO - mozversion platform_buildid: 20160719105020 12:47:33 INFO - mozversion platform_changeset: d1eef136f2523f1726a16c48eace3df02766ac01 12:47:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:47:33 INFO - mozversion platform_version: 50.0a1 12:47:33 INFO - using testdate: 1468957653 12:47:33 INFO - actual date: 1468957653 12:47:33 INFO - starting webserver on 'localhost:49274' 12:47:33 INFO - SUITE-START | Running 2 tests 12:47:33 INFO - TEST-START | dromaeo_css 12:47:33 INFO - operating with platform_type : mac_ 12:47:33 INFO - Initialising browser for dromaeo_css test... 12:47:33 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpDDQs3s/profile http://localhost:49274/getInfo.html) 12:47:36 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 12:47:36 INFO - PROCESS | 1646 | colorDepth:24 12:47:36 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 12:47:36 INFO - PROCESS | 1646 | __metrics 12:47:36 INFO - PROCESS | 1646 | 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" 12:47:36 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:47:37 INFO - TEST-INFO | 1646: exit 0 12:47:37 INFO - Browser initialized. 12:47:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:47:37 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpDDQs3s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:47:49 INFO - PROCESS | 1648 | RSS: Main: 244928512 12:47:49 INFO - PROCESS | 1648 | 12:48:40 INFO - PROCESS | 1648 | 1468957720741 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] 12:50:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 12:50:50 INFO - PROCESS | 1648 | RSS: Main: 321671168 12:50:50 INFO - PROCESS | 1648 | 12:53:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 12:53:54 INFO - PROCESS | 1648 | RSS: Main: 377475072 12:53:54 INFO - PROCESS | 1648 | 12:56:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 12:56:57 INFO - PROCESS | 1648 | RSS: Main: 370507776 12:56:57 INFO - PROCESS | 1648 | 12:59:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 13:00:00 INFO - PROCESS | 1648 | RSS: Main: 371118080 13:00:00 INFO - PROCESS | 1648 | 13:03:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 13:03:04 INFO - PROCESS | 1648 | RSS: Main: 337784832 13:03:04 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 13:06:07 INFO - PROCESS | 1648 | RSS: Main: 426328064 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | __start_tp_report 13:06:07 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 13:06:07 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 13:06:07 INFO - PROCESS | 1648 | |i|pagename|runs| 13:06:07 INFO - PROCESS | 1648 | |0;dojo.html;2760.351317440401;2776.625155603993;2809.424068066046;2817.4365126974612;2844.360018995726;4549.090181963612;4589;4664.440267167936;4695.24876019836;4710.905781884365;4611.907761844761;4622.583967442938;4697.2719228519545;4709.811607535695;4758.8096476141;4244;4247.405363249148;4346.456692913386;4383.517813040563;4436.600705936465;4340.066885619591;4367.519572846984;4384.101259241847;4428.844990425336;4477.037436951055;4223.68322375823;4290.806913688892;4293.957060429387;4321.719088259253;4325.178216138924;4301.763403012833;4322.330038843984;4330.805113769857;4338.826446942118;4351.717138385995;4225.218334608107;4260.786960651955;4327.588879056485;4386.736795792231;4436.068425630621;4620.491745907947;4632.837850675211;4682.695624784378;4685.8594242172785;4715.1512727709;4984.651074424792;5172.741362931838;5190.325257716473;5196.32447781786;5209.947900520984;5149.459306772772;5160.277561141444;5221.582273418117;5242.239875218072;5293.232481290192;4895.68178068428;4899.118158731391;4920.778564964585;4942.752862356903;4956.306117143603;5196.350456192976;5270.235815806686;5283.313169288045;5296.496832800878;5296.602754793329;4990.101781679333;5080.974595127074;5081.339425874613;5081.983603279359;5153.793848246029;5182.97408512955;5184.922226166611;5213.713245771443;5223.660462069954;5249.448807875195;4980.028894365564;5075.264086707407;5100.897982040338;5161.148410512224;5177.404598471224;5102.36220472441;5158.845234642967;5175.974120129451;5177.922331165036;5207.4011488678525;4239.512456067591;4271.957280427156;4289.871303860889;4290.313549832012;4320.984568626371;3757.962420375816;3759.661630453272;3868.6324799144036;3881.3983832506005;3909.863154789569;4461.977690111528;4614.769261536977;4618.422697162855;4621.19129152397;4623.745693986863;5163.483651634881;5183.844484665466;5212.426633070435;5251.186066159751;5258.07983602868;4527.932081018787;4580.1755683976535;4585.679002469795;4603.217453032992;4628.861134165981;4457;4528.139653465897;4578.35902973584;4584.243599805995;4641.234196357564;5383.246345511631;5427.511523962862;5429.457054294539;5452.836414907559;5483.890322193533;5157.252198431132;5183.352080989876;5184.222366645034;5202.2196670499725;5244.160934250502;5225.085610018155;5261.158214685632;5318.0959236930585;5400;5417.431169727122;5071.163258062378;5079.415867175322;5089.491050894881;5097.311862898461;5099.745012749274;5216.165413533817;5219.321488206509;5221.530062294411;5233.869153271046;5235.240890070994;4862.319275301531;4871.805127794849;4890.951090489049;4942.036302920896;5002.199648056294;4972.254161875603;4975.129352363249;4981.078500477423;5013.498650134958;5078.822241221539;4880.902381952412;4883.780229889592;4886.828960986349;4907.288443175791;4931;4876.487968763371;4936.136176169228;4963.404391473046;4986.127427700066;5042.39491261051;4849.175640141114;4878.414590249193;4898.044881248123;4899.412070551557;4931.778069986931 13:06:07 INFO - PROCESS | 1648 | |1;ext.html;425.2091110534407;475.6361383541589;475.69555484489933;482.862384220497;492.0405209840811;30126;40225.3966190507;41187;41231.17537649249;41326.58673413265;42318.788406058;42683.573164268346;42750.358744618854;42763.78618106905;42832.571674283245;9244.260459163268;9424.764380890498;9429.43423394595;9433.339666223366;9437.433753974748;9403.764905877373;9421.434713917188;9511.952440237788;9524.190443812284;9537.380070295443;9480.620775168985;9498.857517137249;9519.428834269931;9532.90467095327;9539.379940303857;9432.669856555021;9510.667126650604;9518.762030949212;9524.428534287965;9534.713958581287;9400.529973501367;9464.810703785955;9476.95261523688;9485.723426377688;9486.810263794685;39442.802785985885;39794.60205397938;39797;39846.60153398487;39914.201715965806;3184.7292980096486;3352.48036554332;3538.982305088484;3576.5708115026364;4171.228322760268;30552.694473055206;31605.367892642018;32014;32110.839445802856;32188;3200.1199670090678;3233.531137984979;3246.788958717699;3255.9837200813845;3306.933861322784;522334;522641;524564;525068;525456.9999999962;51960.28436871941;60990.390096099356;61260;61602;61737;51318.97362052738;63658.68170659117;64199.037014444824;64241.35758642354;68606.31393686098;15800.288986995613;15919.761203581955;15961.760573591406;15978.680426391407;15988.840111598734;11110.055645270242;12369.814452783216;12459.563915263103;12465.626031219077;12492.437840297027;14379.424823007172;14584.270786460847;14644.169478815149;16315.042194936683;16596.336146554244;6018.277806663228;7101.786946391513;7164;7190.06529151207;7236.190952261934;664.6278084272823;745.5787480073849;872.9563521823883;963.5642892090734;1001.397182762443;27341.86329068342;28981;29234.807389445876;29546.556801647992;30019;927.638221093774;951.5860600638745;969.3214749675255;1026.5329275179774;1160.4255893332854;14306.928465357607;14747.262636868329;14992.700145997238;15062.878621268821;15409.614759630875;542407;671945;679701;681266;681772;57967.71016144892;58468.7076564623;58487.70756146277;58511;58589;1250.5373011985623;1341.3092257487206;1364.986350136486;1396.4204854985067;1398.678303990088;22162.77837221607;26549.60175597329;28525.572116417854;28785.424291513635;28960.275993100342;49937.75031124821;50685;50961;51241.7437912808;51270;517659.8234017612;519531;520628;520669;520849;22838.885805570866;23240.535189296457;25611.871940640176;25706.8714656433;25837.870810645825;45554.77222613998;46693;47146.51362754737;48231.517684822706;48313;38295.42556861705;38511.32686084241;38698.80650596729;39117;39229.41155882721;38557.807210964886;38949.278063569946;39025.60974390333;39312.01719957024;39434.40848387334;37691.62308376991;37711.62288377081;37755;39119.41320880246;40448.39327410032;27402.45195096047;33397.66602333946;34482.24246839217;34976.60093596228;35092;25026.74973250244;25189.62215566731;25285.87357063203;25492.3142486197;25829.483410332065 13:06:07 INFO - PROCESS | 1648 | |2;jquery.html;325.49548200289576;348.58866537485767;363.2752658446399;370.586795722769;387.93017256893745;14589.708205835877;15255.389784408624;17068.573285667848;20546;21541.353759387206;20250;20284.797152028474;21585.56828863426;21609.891950540266;21651;5474.835754927339;6029.427204415595;6029.668368239745;6031.879362412749;6035.396460353952;4010.358342665174;4235.0682849773;5055.595552355821;5745.913811292834;5939.020061689837;6603.240627327847;6623.933760662404;6653.567518111331;6655.234648015492;6670.266270710218;6342.429181373675;6668.066470694082;6670.966645166744;6671.466282697421;6676.232233293184;3646.5259516262718;5409.9459005409835;5756.338021127582;5759.827205183851;5799.101139323411;26809.79355928988;27368.315792105157;27548.311292217655;27921;27996.300092497648;1784.1996547354183;2221.3447033125103;2377.453185767266;2562.9743702562923;2619.9345016374555;16753.581160470963;16945;17106.572335691708;17242.482725518254;17452.563685907826;1712.2637265975625;1846.9261229550802;1931.7681878174708;1993.252530301137;2029.985007496252;137916;145601.9039333629;147621;149542;149809.25095374454;15128.39486420531;20085.698714519294;20710.825363336633;20851.142538630404;20885.89557052205;33879.66120338815;34391.828040860135;34450.7942222021;34515.482267766005;34588.61645534201;10412.5834966602;10636.30863993838;10683.786324273471;10726.356418614909;11247.887521124847;8881.511516866505;8882.555872206363;8900.688475903442;9003.729888103368;9012.41419307743;9228.60197220526;9348.859767103499;9464.574094165779;9472.242220622335;9479.431234125837;2999.5950546676354;3301.490866941142;3502.527158833525;3614.6137080642197;3638.780343990164;707.5719189890145;755.8110472381904;757.2162811490082;799.9680012799423;816.848882956655;21034.47413814667;21305;22123;22140.778592214192;22418.88790556037;788.7712563356562;830.5276088264737;847.7583888591722;868.2099289646392;889.088684098791;10097.394156350496;10917.344959302467;11163.83254251187;11191.608293709682;11267.887321126684;506415;577685.3388796686;581631;587432;592279;129026.35486822505;135649;135827;138831;139025;937.5546615357787;948.1135138645388;967.1585720423277;967.9177269931994;974.1476208317722;17161.656766864842;18406.72389914179;18666.879987200813;18760.906195468935;18988.810111899256;41833;53766.19350709664;59441.70279148577;59732.50668733317;59934.70032649809;80330;81164.59417702873;81211;81486;82454.17545824705;6951.478639101986;7260.128784545888;7268;7365.8158546036775;7956.2441568050945;9109.498977556297;13347.933260333637;13380.397882095134;13466.596002119952;13506.729865402833;11173.888261117285;11250.324980501196;11428.25716328436;11610.071194304604;11676.88323116758;10679.839802403125;11042.392668402994;11209.71975700614;11437.828432573684;11491.482883270106;11472;11492.94253528727;11663.533458661897;11781;11819.468123934272;8106.837863242584;8202.056763472277;8321.750347489586;8329.750107496784;8336.208060234268;10376.16990640762;11367.20429569922;11501.424928753697;11681.532738690697;11694.649160525198 13:06:07 INFO - PROCESS | 1648 | |3;mootools.html;1474.6681996550774;1503.7593984962405;1637.402348142928;1781.8396344329008;1806.1420825108066;8166.142555031718;8446.662133514652;8612.784680382985;8625.137486251371;8627.439216450926;7562.432817538679;7794.727184548542;8179.304759095484;8262.834743305146;8414.747557573268;1938.5638231397893;2210.7899749523767;2244.2145249162827;2246.8764217968005;2250.9437264068447;2180.9563808723815;2207.172310383606;2264.3772962435296;2266.150193677371;2266.3880752196897;1935.090507461491;2028.7971202879667;2128.936131916045;2204.9779502205015;2210.6794514795347;2248.718910136233;2263.2531264682616;2269.4780200553964;2270.6821045053707;2270.8751018693965;2216.8669879807103;2242.9775702242932;2246.6630005499305;2247.213475283654;2251.752307246202;8552.315814734806;8622.870656940151;8629.00766411858;8632.309415246766;8646.870296945552;4892.486288939681;5077.746112694351;5137.5101220645965;5149.407818100929;5157.149070403379;3398.371301309241;3413.6586341365664;3452.7065199457825;3513.5959364673135;3533.0460775590705;1054.4833031814433;1059.7721489879714;1075.1184029096144;1081.1566977757361;1085.137315833914;11019.559217631366;11300.773984520345;11309.717257068556;11516.25144365622;11524.769504609945;4162.791860406967;4250.23495770758;4340.696151269444;4380.2772542530765;4400.317950717644;8985.595648195847;8998.955005225063;9007.819843603222;9010.189082982562;9012.053734357798;5041.294218809369;5045.344105266292;5045.445001049881;5047;5047.646664733434;4566.88582785433;4709.128811169944;4715.292706094045;4718.41019872516;4782.450018247877;3402.1324562236473;3407.3355695639607;3410.5395771571016;3456.6197718250974;3508.824558772051;861.7371701630938;1042.6507120114695;1049.5486940615456;1052.2686732720686;1052.594751020847;572.6449601247253;609.6646844235742;775.2634996752994;781.7732857471269;816.9918300816915;5460.5085542300585;5521.199426083276;5737.51231145349;5913.76344946206;6038.305594856648;840.9537475438788;869.0917990699734;873.1879352202512;883.1830556734994;906.9682561110329;3786.1291902861944;3899.2396482685604;3943.507061617298;3946.8421263149726;3960.2277555876904;8902.955485222661;10043.347182433119;10506.684799456028;10568.207384446197;10574.312669676447;10111.241656875764;10513;10530.104941080093;10530.473476326153;10541.578336866593;7762.029746281715;7819.100803407681;7921.5247085176225;7924.1283458820635;7928.405369597303;5049.394072711299;5129.615278854027;5590.692511911884;5623.2408624835125;5700.372958974593;9316.48759318244;9610.135087841849;9636.662716804878;9783.021697830445;9844.950775246078;9489.145976862115;9909.65316213929;10188.388696678223;10188.592456301665;10194.133498652402;7223.711051558089;7300.379467745302;7411.888821667787;7413.369863561657;7422.925770742223;7979.680812767424;8007.879881801894;8061.2744852963515;8069.677212911418;8093.31206847401;6010.639361638316;6637.767678131242;6808.012838138541;7199.3520583147765;7453;7088.468364872759;7247.746328878675;7283.344498995116;7294.088238970129;7442.627868606656;6757.222919364336;6771.052052712525;6838.487113466411;6847;6873.931260687329;4574.862754117382;4610.492845786894;4640.675152739276;4666.696664716783;4724.480307166277;4903.583195428429;4911.459739428588;4967.950320496749;5003.199488081889;5048.697078175322 13:06:07 INFO - PROCESS | 1648 | |4;prototype.html;279.9446088247307;288.8786709581975;289.968768774733;294.2643391521197;296.6306947849927;3166.3825554016976;4136.67626359565;4171.541130475649;4175.728577642459;4183.205191013709;3618.945715814265;3874.961250387495;4142.668586513079;4144.357624568197;4150.584941505848;2795.7838340321923;3036.180231337543;3066.9604712865917;3154.9842250788715;3186.8686616251243;3207.4547326954475;3268.1992911736656;3278.1476816027885;3281.7210537104374;3295.1597342677705;2784.540550809114;3230.144011836865;3239.271163988107;3241.75686823488;3287.934241315172;3058.149819359289;3108.818648913421;3204.4071846708434;3258.804471731704;3260.8043517389033;2876.590470669357;2881.236472334833;2882.495563276418;2892.0022592205655;2905.3462970831606;3908.8045597720284;3911.393733971239;3923.5880232575455;3929.98035009826;3964.5638979712207;3698.4822124902535;3700.40793473043;3719.163188282642;3722.590515043343;3740.9625903740884;4115.567865374122;4323.6757243206885;4337.82648694055;4360.514506731164;4384.517703052694;3194.9201269968203;3216.4049650814673;3236.1100697308193;3237.983810080958;3253.5760858160584;4531.592156705912;4538.978729785772;4557.564367224347;4575.084983003413;4588.839390621346;2720.238333266649;3203.981491660286;3274.5251938468787;3283.7044665980175;3287.539744435781;2745.6567929008875;3275.0349790125733;3299.1916980339493;3301.570795796579;3315.353506066294;2835.659720833472;3227.8547465364113;3309.569755931762;3491.17957280039;3721.3020560093714;3750.393476533837;3953.7430067046125;4360.629346505582;4389.517153113155;4391.978040109779;968.6173961285253;1115.7949414632178;1144.9084073274119;1147.386148410613;1149.8850114988434;717.4690728860605;717.6375930155239;734.9563619660096;738.6011553760909;793.6666600028002;3514.332276867387;3778.3387907116694;3897.493325867619;3929.862454814068;3964.682825373963;4057.5739547347694;4488.1472520221705;4491.46102467706;4495.415595972503;4499.167653984024;3277.360914621626;3293.8517766700556;4003.3594624860466;4008.599140086026;4015.3374693175297;2162.653975363966;2324.988375058114;2348.4481146930457;2360.350903501551;2383.699729760789;4646.514114948486;4663.090697313991;4664.113818374498;4668.52941323482;4697.929531057037;3998.28030954431;4458.821647134078;4556.749378784199;4646.907061858744;4647.930281045787;3490.598581163097;3630.5280313560124;4610.4467463903875;4671.766411679402;4675.322078298627;4515.367848501278;4546.954530454653;4547.727136371828;4553.453585569753;4562.406887104655;3894.82473288697;4103.4870641169855;4566.337881007169;4571.1771881060395;4576.954230457653;4150.941509915073;4237.131388065501;4474.395956545824;4545.931811022771;4744.857654270378;3154.495280755068;3174.206448387903;3198.696123868229;3200.6639302872863;3205.6313523945046;3517.9296414071064;4042.8477883802975;4325.264705000221;4488.349189367589;4594.494605593449;3791.526059242595;4405.889852753707;4449.644028477649;4454.376387305715;4473.060657262012;3008.2737371599846;4111.958880411157;4334.306510958231;4417.624501917373;4455.888602784957;3518.472229165646;4236.597523235288;4286.271333873186;4296.968727505438;4330.5236423993965;4039.9496131005562;4126.122614210467;4395.7362558246605;4431.158079964796;4499.662525310549;3486.825658717003;3655.6892664123848;4306.868100290376;4314.892127696833;4314.9784251078545 13:06:07 INFO - PROCESS | 1648 | |5;yui.html;702.9367356937875;713.9393151582116;728.2826415980259;763.2062654838966;794.3565711773467;14552.56342309731;15597.064176149424;15610.98528595642;15652.686946261068;15690.529284121494;12995.220286782776;13095.27975961324;13119.868801311984;13179.802302965463;13202;685.1093578348152;705.3933617089299;860.9268212201955;868.3357231717741;883.8630012348065;874.4359887872318;878.1438097854573;879.160401816264;879.1779685993585;879.2394578689419;913.0595486648763;929.1034152043277;931.2317338195982;931.804321092568;934.8364036293656;724.8550289942033;915.4278575890069;927.4064598656831;928.9442633442015;935.4808081514757;668.993310066898;669.243754557352;671.3320246354859;684.9212340580797;687.089606271687;15133;15162.469313574082;15182.544523664308;15189.924050379677;15203.923980380026;14344.354504047344;14700.926495367561;14706.411743530245;14740.484083057041;15013.774793378108;11609.245399049034;11859.347735874524;12032.097592680497;12058.247128575309;12069.637910862688;3708.1100535871196;3721.832517536717;3908.1402091539817;3926.7840268785208;3937.9015524611827;79571;103567.4821625887;104599.43100853493;104763.85708428759;105087;11110.888891111146;11217.549621236447;11314.490847911864;11392.94303528477;11614.361210133524;23399.298021059396;23416.414589635395;23428.18001369944;23428.765712342658;23433.41416464568;7687.2697093776005;8058.637361318754;8179.427440079137;8198;8381.622826972802;4382.298832186899;4449.755263460476;4470.418845550058;4481.7983190756495;4642.048380082075;3405.6934875861293;3535.6641119093647;3555.8755443559353;3558.1282585766135;3558.4128618777613;554.9223108764776;560.938296787353;563.1355868741463;573.6156774960787;575.3125015606394;492.41648646354184;548.7091841324104;574.5317566183547;579.3192998227066;581.5463938128266;8638.308935285162;8651.783705407415;8744.256738177199;8807.691730789391;9057.47286182998;518.9299444575009;547.663187139914;569.1235497334059;574.4456599381533;585.2245774349004;4154.937675934863;4417.536158703354;4813.78337974792;5113.948860511422;5119.10415677255;87988.5600571993;95739;118802;124941;160430;130205;134248;134664;136647;136953.89138325865;97356;97496.02503975011;105027;112236;114543.2818507723;7514.812129696801;9606.759831004281;9618.855717164388;9750.707478775648;9762.707118786448;58280.83438331295;60925.83572728025;61012.69493652503;61908.380916192065;63256.36743632689;89825.55087224784;92225.69435763892;92623.53688231515;93688;94375;12707.237565746085;13072.150310229805;13209.801852972405;13244.337783111;13358.933205333911;13958.23229722375;14924.62688432798;15079.849201507845;15301.940157792973;15805;8050.59575806276;12476.68808279764;13157.144785588906;14063.437462501386;14208.218547979961;13253.536126235536;13515.189088654712;13583.77746002825;14151.787723184367;14450.85549144495;13574.389152488358;13723.725525489634;13828.446862125402;13927.58217253484;14205.28973551339;11210.775784484427;11273.71815704614;11300.835765985537;11367.20429569922;11504.137189710973;10824;11225.831612525983;11271.211015229183;11429.828552571884;11433.371164585695 13:06:07 INFO - PROCESS | 1648 | __end_tp_report 13:06:07 INFO - PROCESS | 1648 | __start_cc_report 13:06:07 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1229 13:06:07 INFO - PROCESS | 1648 | __end_cc_report 13:06:07 INFO - PROCESS | 1648 | __startTimestamp1468958767787__endTimestamp 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | ------- Summary: start ------- 13:06:07 INFO - PROCESS | 1648 | Number of tests: 6 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:4775.00 Median:4899.41 stddev:521.00 (10.6%) stddev-sans-first:498.04 13:06:07 INFO - PROCESS | 1648 | Values: 2760.4 2776.6 2809.4 2817.4 2844.4 4549.1 4589.0 4664.4 4695.2 4710.9 4611.9 4622.6 4697.3 4709.8 4758.8 4244.0 4247.4 4346.5 4383.5 4436.6 4340.1 4367.5 4384.1 4428.8 4477.0 4223.7 4290.8 4294.0 4321.7 4325.2 4301.8 4322.3 4330.8 4338.8 4351.7 4225.2 4260.8 4327.6 4386.7 4436.1 4620.5 4632.8 4682.7 4685.9 4715.2 4984.7 5172.7 5190.3 5196.3 5209.9 5149.5 5160.3 5221.6 5242.2 5293.2 4895.7 4899.1 4920.8 4942.8 4956.3 5196.4 5270.2 5283.3 5296.5 5296.6 4990.1 5081.0 5081.3 5082.0 5153.8 5183.0 5184.9 5213.7 5223.7 5249.4 4980.0 5075.3 5100.9 5161.1 5177.4 5102.4 5158.8 5176.0 5177.9 5207.4 4239.5 4272.0 4289.9 4290.3 4321.0 3758.0 3759.7 3868.6 3881.4 3909.9 4462.0 4614.8 4618.4 4621.2 4623.7 5163.5 5183.8 5212.4 5251.2 5258.1 4527.9 4580.2 4585.7 4603.2 4628.9 4457.0 4528.1 4578.4 4584.2 4641.2 5383.2 5427.5 5429.5 5452.8 5483.9 5157.3 5183.4 5184.2 5202.2 5244.2 5225.1 5261.2 5318.1 5400.0 5417.4 5071.2 5079.4 5089.5 5097.3 5099.7 5216.2 5219.3 5221.5 5233.9 5235.2 4862.3 4871.8 4891.0 4942.0 5002.2 4972.3 4975.1 4981.1 5013.5 5078.8 4880.9 4883.8 4886.8 4907.3 4931.0 4876.5 4936.1 4963.4 4986.1 5042.4 4849.2 4878.4 4898.0 4899.4 4931.8 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:69587.42 Median:41278.88 stddev:152202.45 (368.7%) stddev-sans-first:152540.84 13:06:07 INFO - PROCESS | 1648 | Values: 425.2 475.6 475.7 482.9 492.0 30126.0 40225.4 41187.0 41231.2 41326.6 42318.8 42683.6 42750.4 42763.8 42832.6 9244.3 9424.8 9429.4 9433.3 9437.4 9403.8 9421.4 9512.0 9524.2 9537.4 9480.6 9498.9 9519.4 9532.9 9539.4 9432.7 9510.7 9518.8 9524.4 9534.7 9400.5 9464.8 9477.0 9485.7 9486.8 39442.8 39794.6 39797.0 39846.6 39914.2 3184.7 3352.5 3539.0 3576.6 4171.2 30552.7 31605.4 32014.0 32110.8 32188.0 3200.1 3233.5 3246.8 3256.0 3306.9 522334.0 522641.0 524564.0 525068.0 525457.0 51960.3 60990.4 61260.0 61602.0 61737.0 51319.0 63658.7 64199.0 64241.4 68606.3 15800.3 15919.8 15961.8 15978.7 15988.8 11110.1 12369.8 12459.6 12465.6 12492.4 14379.4 14584.3 14644.2 16315.0 16596.3 6018.3 7101.8 7164.0 7190.1 7236.2 664.6 745.6 873.0 963.6 1001.4 27341.9 28981.0 29234.8 29546.6 30019.0 927.6 951.6 969.3 1026.5 1160.4 14306.9 14747.3 14992.7 15062.9 15409.6 542407.0 671945.0 679701.0 681266.0 681772.0 57967.7 58468.7 58487.7 58511.0 58589.0 1250.5 1341.3 1365.0 1396.4 1398.7 22162.8 26549.6 28525.6 28785.4 28960.3 49937.8 50685.0 50961.0 51241.7 51270.0 517659.8 519531.0 520628.0 520669.0 520849.0 22838.9 23240.5 25611.9 25706.9 25837.9 45554.8 46693.0 47146.5 48231.5 48313.0 38295.4 38511.3 38698.8 39117.0 39229.4 38557.8 38949.3 39025.6 39312.0 39434.4 37691.6 37711.6 37755.0 39119.4 40448.4 27402.5 33397.7 34482.2 34976.6 35092.0 25026.7 25189.6 25285.9 25492.3 25829.5 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:36624.61 Median:1813.49 stddev:96249.90 (5307.4%) stddev-sans-first:96481.32 13:06:07 INFO - PROCESS | 1648 | Values: 325.5 348.6 363.3 370.6 387.9 14589.7 15255.4 17068.6 20546.0 21541.4 20250.0 20284.8 21585.6 21609.9 21651.0 5474.8 6029.4 6029.7 6031.9 6035.4 4010.4 4235.1 5055.6 5745.9 5939.0 6603.2 6623.9 6653.6 6655.2 6670.3 6342.4 6668.1 6671.0 6671.5 6676.2 3646.5 5409.9 5756.3 5759.8 5799.1 26809.8 27368.3 27548.3 27921.0 27996.3 1784.2 2221.3 2377.5 2563.0 2619.9 16753.6 16945.0 17106.6 17242.5 17452.6 1712.3 1846.9 1931.8 1993.3 2030.0 137916.0 145601.9 147621.0 149542.0 149809.3 15128.4 20085.7 20710.8 20851.1 20885.9 33879.7 34391.8 34450.8 34515.5 34588.6 10412.6 10636.3 10683.8 10726.4 11247.9 8881.5 8882.6 8900.7 9003.7 9012.4 9228.6 9348.9 9464.6 9472.2 9479.4 2999.6 3301.5 3502.5 3614.6 3638.8 707.6 755.8 757.2 800.0 816.8 21034.5 21305.0 22123.0 22140.8 22418.9 788.8 830.5 847.8 868.2 889.1 10097.4 10917.3 11163.8 11191.6 11267.9 506415.0 577685.3 581631.0 587432.0 592279.0 129026.4 135649.0 135827.0 138831.0 139025.0 937.6 948.1 967.2 967.9 974.1 17161.7 18406.7 18666.9 18760.9 18988.8 41833.0 53766.2 59441.7 59732.5 59934.7 80330.0 81164.6 81211.0 81486.0 82454.2 6951.5 7260.1 7268.0 7365.8 7956.2 9109.5 13347.9 13380.4 13466.6 13506.7 11173.9 11250.3 11428.3 11610.1 11676.9 10679.8 11042.4 11209.7 11437.8 11491.5 11472.0 11492.9 11663.5 11781.0 11819.5 8106.8 8202.1 8321.8 8329.8 8336.2 10376.2 11367.2 11501.4 11681.5 11694.6 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5469.53 Median:5022.25 stddev:3107.31 (61.9%) stddev-sans-first:3101.52 13:06:07 INFO - PROCESS | 1648 | Values: 1474.7 1503.8 1637.4 1781.8 1806.1 8166.1 8446.7 8612.8 8625.1 8627.4 7562.4 7794.7 8179.3 8262.8 8414.7 1938.6 2210.8 2244.2 2246.9 2250.9 2181.0 2207.2 2264.4 2266.2 2266.4 1935.1 2028.8 2128.9 2205.0 2210.7 2248.7 2263.3 2269.5 2270.7 2270.9 2216.9 2243.0 2246.7 2247.2 2251.8 8552.3 8622.9 8629.0 8632.3 8646.9 4892.5 5077.7 5137.5 5149.4 5157.1 3398.4 3413.7 3452.7 3513.6 3533.0 1054.5 1059.8 1075.1 1081.2 1085.1 11019.6 11300.8 11309.7 11516.3 11524.8 4162.8 4250.2 4340.7 4380.3 4400.3 8985.6 8999.0 9007.8 9010.2 9012.1 5041.3 5045.3 5045.4 5047.0 5047.6 4566.9 4709.1 4715.3 4718.4 4782.5 3402.1 3407.3 3410.5 3456.6 3508.8 861.7 1042.7 1049.5 1052.3 1052.6 572.6 609.7 775.3 781.8 817.0 5460.5 5521.2 5737.5 5913.8 6038.3 841.0 869.1 873.2 883.2 907.0 3786.1 3899.2 3943.5 3946.8 3960.2 8903.0 10043.3 10506.7 10568.2 10574.3 10111.2 10513.0 10530.1 10530.5 10541.6 7762.0 7819.1 7921.5 7924.1 7928.4 5049.4 5129.6 5590.7 5623.2 5700.4 9316.5 9610.1 9636.7 9783.0 9845.0 9489.1 9909.7 10188.4 10188.6 10194.1 7223.7 7300.4 7411.9 7413.4 7422.9 7979.7 8007.9 8061.3 8069.7 8093.3 6010.6 6637.8 6808.0 7199.4 7453.0 7088.5 7247.7 7283.3 7294.1 7442.6 6757.2 6771.1 6838.5 6847.0 6873.9 4574.9 4610.5 4640.7 4666.7 4724.5 4903.6 4911.5 4968.0 5003.2 5048.7 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:3564.44 Median:3929.92 stddev:1060.64 (27.0%) stddev-sans-first:1034.57 13:06:07 INFO - PROCESS | 1648 | Values: 279.9 288.9 290.0 294.3 296.6 3166.4 4136.7 4171.5 4175.7 4183.2 3618.9 3875.0 4142.7 4144.4 4150.6 2795.8 3036.2 3067.0 3155.0 3186.9 3207.5 3268.2 3278.1 3281.7 3295.2 2784.5 3230.1 3239.3 3241.8 3287.9 3058.1 3108.8 3204.4 3258.8 3260.8 2876.6 2881.2 2882.5 2892.0 2905.3 3908.8 3911.4 3923.6 3930.0 3964.6 3698.5 3700.4 3719.2 3722.6 3741.0 4115.6 4323.7 4337.8 4360.5 4384.5 3194.9 3216.4 3236.1 3238.0 3253.6 4531.6 4539.0 4557.6 4575.1 4588.8 2720.2 3204.0 3274.5 3283.7 3287.5 2745.7 3275.0 3299.2 3301.6 3315.4 2835.7 3227.9 3309.6 3491.2 3721.3 3750.4 3953.7 4360.6 4389.5 4392.0 968.6 1115.8 1144.9 1147.4 1149.9 717.5 717.6 735.0 738.6 793.7 3514.3 3778.3 3897.5 3929.9 3964.7 4057.6 4488.1 4491.5 4495.4 4499.2 3277.4 3293.9 4003.4 4008.6 4015.3 2162.7 2325.0 2348.4 2360.4 2383.7 4646.5 4663.1 4664.1 4668.5 4697.9 3998.3 4458.8 4556.7 4646.9 4647.9 3490.6 3630.5 4610.4 4671.8 4675.3 4515.4 4547.0 4547.7 4553.5 4562.4 3894.8 4103.5 4566.3 4571.2 4577.0 4150.9 4237.1 4474.4 4545.9 4744.9 3154.5 3174.2 3198.7 3200.7 3205.6 3517.9 4042.8 4325.3 4488.3 4594.5 3791.5 4405.9 4449.6 4454.4 4473.1 3008.3 4112.0 4334.3 4417.6 4455.9 3518.5 4236.6 4286.3 4297.0 4330.5 4039.9 4126.1 4395.7 4431.2 4499.7 3486.8 3655.7 4306.9 4314.9 4315.0 13:06:07 INFO - PROCESS | 1648 | 13:06:07 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:23769.95 Median:3814.99 stddev:36945.14 (968.4%) stddev-sans-first:37008.18 13:06:07 INFO - PROCESS | 1648 | Values: 702.9 713.9 728.3 763.2 794.4 14552.6 15597.1 15611.0 15652.7 15690.5 12995.2 13095.3 13119.9 13179.8 13202.0 685.1 705.4 860.9 868.3 883.9 874.4 878.1 879.2 879.2 879.2 913.1 929.1 931.2 931.8 934.8 724.9 915.4 927.4 928.9 935.5 669.0 669.2 671.3 684.9 687.1 15133.0 15162.5 15182.5 15189.9 15203.9 14344.4 14700.9 14706.4 14740.5 15013.8 11609.2 11859.3 12032.1 12058.2 12069.6 3708.1 3721.8 3908.1 3926.8 3937.9 79571.0 103567.5 104599.4 104763.9 105087.0 11110.9 11217.5 11314.5 11392.9 11614.4 23399.3 23416.4 23428.2 23428.8 23433.4 7687.3 8058.6 8179.4 8198.0 8381.6 4382.3 4449.8 4470.4 4481.8 4642.0 3405.7 3535.7 3555.9 3558.1 3558.4 554.9 560.9 563.1 573.6 575.3 492.4 548.7 574.5 579.3 581.5 8638.3 8651.8 8744.3 8807.7 9057.5 518.9 547.7 569.1 574.4 585.2 4154.9 4417.5 4813.8 5113.9 5119.1 87988.6 95739.0 118802.0 124941.0 160430.0 130205.0 134248.0 134664.0 136647.0 136953.9 97356.0 97496.0 105027.0 112236.0 114543.3 7514.8 9606.8 9618.9 9750.7 9762.7 58280.8 60925.8 61012.7 61908.4 63256.4 89825.6 92225.7 92623.5 93688.0 94375.0 12707.2 13072.2 13209.8 13244.3 13358.9 13958.2 14924.6 15079.8 15301.9 15805.0 8050.6 12476.7 13157.1 14063.4 14208.2 13253.5 13515.2 13583.8 14151.8 14450.9 13574.4 13723.7 13828.4 13927.6 14205.3 11210.8 11273.7 11300.8 11367.2 11504.1 10824.0 11225.8 11271.2 11429.8 11433.4 13:06:07 INFO - PROCESS | 1648 | -------- Summary: end -------- 13:06:07 INFO - PROCESS | 1648 | 13:06:08 INFO - TEST-INFO | 1648: exit 0 13:06:08 INFO - TEST-OK | dromaeo_css | took 1114646ms 13:06:08 INFO - TEST-START | kraken 13:06:08 INFO - operating with platform_type : mac_ 13:06:08 INFO - Initialising browser for kraken test... 13:06:08 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpPDg4qn/profile http://localhost:49274/getInfo.html) 13:06:10 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 13:06:10 INFO - PROCESS | 1658 | colorDepth:24 13:06:10 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 13:06:10 INFO - PROCESS | 1658 | __metrics 13:06:10 INFO - PROCESS | 1658 | 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" 13:06:10 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:06:11 INFO - TEST-INFO | 1658: exit 0 13:06:11 INFO - Browser initialized. 13:06:11 INFO - Running cycle 1/1 for kraken test... 13:06:11 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpPDg4qn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:06:23 INFO - PROCESS | 1659 | RSS: Main: 245063680 13:06:23 INFO - PROCESS | 1659 | 13:06:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 13:06:27 INFO - PROCESS | 1659 | RSS: Main: 295337984 13:06:27 INFO - PROCESS | 1659 | 13:06:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 13:06:30 INFO - PROCESS | 1659 | RSS: Main: 304590848 13:06:30 INFO - PROCESS | 1659 | 13:06:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 13:06:35 INFO - PROCESS | 1659 | RSS: Main: 392974336 13:06:35 INFO - PROCESS | 1659 | 13:06:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 13:06:37 INFO - PROCESS | 1659 | RSS: Main: 291295232 13:06:37 INFO - PROCESS | 1659 | 13:06:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:06:40 INFO - PROCESS | 1659 | RSS: Main: 295206912 13:06:40 INFO - PROCESS | 1659 | 13:06:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 13:06:44 INFO - PROCESS | 1659 | RSS: Main: 332763136 13:06:44 INFO - PROCESS | 1659 | 13:06:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 13:06:47 INFO - PROCESS | 1659 | RSS: Main: 333447168 13:06:47 INFO - PROCESS | 1659 | 13:06:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 13:06:51 INFO - PROCESS | 1659 | RSS: Main: 333193216 13:06:51 INFO - PROCESS | 1659 | 13:06:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:06:53 INFO - PROCESS | 1659 | RSS: Main: 313446400 13:06:53 INFO - PROCESS | 1659 | 13:06:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:06:56 INFO - PROCESS | 1659 | RSS: Main: 320319488 13:06:56 INFO - PROCESS | 1659 | 13:06:58 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:06:59 INFO - PROCESS | 1659 | RSS: Main: 317612032 13:06:59 INFO - PROCESS | 1659 | 13:07:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:07:02 INFO - PROCESS | 1659 | RSS: Main: 327479296 13:07:02 INFO - PROCESS | 1659 | 13:07:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:07:06 INFO - PROCESS | 1659 | RSS: Main: 328855552 13:07:06 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 13:07:08 INFO - PROCESS | 1659 | RSS: Main: 371994624 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | __start_tp_report 13:07:08 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 13:07:08 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 13:07:08 INFO - PROCESS | 1659 | |i|pagename|runs| 13:07:08 INFO - PROCESS | 1659 | |0;ai-astar;104;117;115;115;116;117;117;115;116;115 13:07:08 INFO - PROCESS | 1659 | |1;audio-beat-detection;149;109;112;171;124;112;137;106;151;111 13:07:08 INFO - PROCESS | 1659 | |2;audio-dft;216;214;212;213;212;248;237;211;210;208 13:07:08 INFO - PROCESS | 1659 | |3;audio-fft;84;78;60;91;61;59;66;64;85;58 13:07:08 INFO - PROCESS | 1659 | |4;audio-oscillator;85;91;96;74;78;101;133;86;70;103 13:07:08 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;94;88;92;89;92;88;91;89;92;88 13:07:08 INFO - PROCESS | 1659 | |6;imaging-darkroom;97;97;97;98;97;138;157;98;97;97 13:07:08 INFO - PROCESS | 1659 | |7;imaging-desaturate;105;107;102;127;165;160;101;106;106;106 13:07:08 INFO - PROCESS | 1659 | |8;json-parse-financial;57;55;54;55;55;54;55;54;53;56 13:07:08 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;73;75;83;80;80;78;79;77;80;78 13:07:08 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;88;90;87;85;87;85;87;85;88;89 13:07:08 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;143;151;154;154;150;141;143;143;179;142 13:07:08 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;170;175;238;202;171;172;177;170;178;172 13:07:08 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;68;70;70;70;68;69;68;67;67;69 13:07:08 INFO - PROCESS | 1659 | __end_tp_report 13:07:08 INFO - PROCESS | 1659 | __start_cc_report 13:07:08 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2096 13:07:08 INFO - PROCESS | 1659 | __end_cc_report 13:07:08 INFO - PROCESS | 1659 | __startTimestamp1468958828648__endTimestamp 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | ------- Summary: start ------- 13:07:08 INFO - PROCESS | 1659 | Number of tests: 14 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.00 stddev:3.86 (3.3%) stddev-sans-first:0.93 13:07:08 INFO - PROCESS | 1659 | Values: 104.0 117.0 115.0 115.0 116.0 117.0 117.0 115.0 116.0 115.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:130.50 stddev:22.51 (17.3%) stddev-sans-first:22.59 13:07:08 INFO - PROCESS | 1659 | Values: 149.0 109.0 112.0 171.0 124.0 112.0 137.0 106.0 151.0 111.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:218.10 Median:213.50 stddev:13.30 (6.2%) stddev-sans-first:14.08 13:07:08 INFO - PROCESS | 1659 | Values: 216.0 214.0 212.0 213.0 212.0 248.0 237.0 211.0 210.0 208.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:70.60 Median:72.00 stddev:12.56 (17.4%) stddev-sans-first:12.35 13:07:08 INFO - PROCESS | 1659 | Values: 84.0 78.0 60.0 91.0 61.0 59.0 66.0 64.0 85.0 58.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:81.50 stddev:18.22 (22.4%) stddev-sans-first:19.16 13:07:08 INFO - PROCESS | 1659 | Values: 85.0 91.0 96.0 74.0 78.0 101.0 133.0 86.0 70.0 103.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:91.50 stddev:2.16 (2.4%) stddev-sans-first:1.83 13:07:08 INFO - PROCESS | 1659 | Values: 94.0 88.0 92.0 89.0 92.0 88.0 91.0 89.0 92.0 88.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:97.00 stddev:21.66 (22.3%) stddev-sans-first:22.65 13:07:08 INFO - PROCESS | 1659 | Values: 97.0 97.0 97.0 98.0 97.0 138.0 157.0 98.0 97.0 97.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:118.50 Median:106.50 stddev:24.31 (22.8%) stddev-sans-first:25.29 13:07:08 INFO - PROCESS | 1659 | Values: 105.0 107.0 102.0 127.0 165.0 160.0 101.0 106.0 106.0 106.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:1.14 (2.1%) stddev-sans-first:0.88 13:07:08 INFO - PROCESS | 1659 | Values: 57.0 55.0 54.0 55.0 55.0 54.0 55.0 54.0 53.0 56.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:79.50 stddev:2.83 (3.6%) stddev-sans-first:2.26 13:07:08 INFO - PROCESS | 1659 | Values: 73.0 75.0 83.0 80.0 80.0 78.0 79.0 77.0 80.0 78.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.50 stddev:1.73 (2.0%) stddev-sans-first:1.80 13:07:08 INFO - PROCESS | 1659 | Values: 88.0 90.0 87.0 85.0 87.0 85.0 87.0 85.0 88.0 89.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:150.50 stddev:11.38 (7.6%) stddev-sans-first:11.79 13:07:08 INFO - PROCESS | 1659 | Values: 143.0 151.0 154.0 154.0 150.0 141.0 143.0 143.0 179.0 142.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.50 Median:176.00 stddev:21.69 (12.3%) stddev-sans-first:22.52 13:07:08 INFO - PROCESS | 1659 | Values: 170.0 175.0 238.0 202.0 171.0 172.0 177.0 170.0 178.0 172.0 13:07:08 INFO - PROCESS | 1659 | 13:07:08 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.17 (1.7%) stddev-sans-first:1.22 13:07:08 INFO - PROCESS | 1659 | Values: 68.0 70.0 70.0 70.0 68.0 69.0 68.0 67.0 67.0 69.0 13:07:08 INFO - PROCESS | 1659 | -------- Summary: end -------- 13:07:08 INFO - PROCESS | 1659 | 13:07:09 INFO - TEST-INFO | 1659: exit 0 13:07:09 INFO - TEST-OK | kraken | took 61160ms 13:07:09 INFO - SUITE-END | took 1175s 13:07:09 INFO - Completed test suite (00:19:36) 13:07:09 INFO - javascript benchmark 13:07:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2760.351317440401, 2776.625155603993, 2809.424068066046, 2817.4365126974612, 2844.360018995726, 4549.090181963612, 4589.0, 4664.440267167936, 4695.24876019836, 4710.905781884365, 4611.907761844761, 4622.583967442938, 4697.2719228519545, 4709.811607535695, 4758.8096476141, 4244.0, 4247.405363249148, 4346.456692913386, 4383.517813040563, 4436.600705936465, 4340.066885619591, 4367.519572846984, 4384.101259241847, 4428.844990425336, 4477.037436951055, 4223.68322375823, 4290.806913688892, 4293.957060429387, 4321.719088259253, 4325.178216138924, 4301.763403012833, 4322.330038843984, 4330.805113769857, 4338.826446942118, 4351.717138385995, 4225.218334608107, 4260.786960651955, 4327.588879056485, 4386.736795792231, 4436.068425630621, 4620.491745907947, 4632.837850675211, 4682.695624784378, 4685.8594242172785, 4715.1512727709, 4984.651074424792, 5172.741362931838, 5190.325257716473, 5196.32447781786, 5209.947900520984, 5149.459306772772, 5160.277561141444, 5221.582273418117, 5242.239875218072, 5293.232481290192, 4895.68178068428, 4899.118158731391, 4920.778564964585, 4942.752862356903, 4956.306117143603, 5196.350456192976, 5270.235815806686, 5283.313169288045, 5296.496832800878, 5296.602754793329, 4990.101781679333, 5080.974595127074, 5081.339425874613, 5081.983603279359, 5153.793848246029, 5182.97408512955, 5184.922226166611, 5213.713245771443, 5223.660462069954, 5249.448807875195, 4980.028894365564, 5075.264086707407, 5100.897982040338, 5161.148410512224, 5177.404598471224, 5102.36220472441, 5158.845234642967, 5175.974120129451, 5177.922331165036, 5207.4011488678525, 4239.512456067591, 4271.957280427156, 4289.871303860889, 4290.313549832012, 4320.984568626371, 3757.962420375816, 3759.661630453272, 3868.6324799144036, 3881.3983832506005, 3909.863154789569, 4461.977690111528, 4614.769261536977, 4618.422697162855, 4621.19129152397, 4623.745693986863, 5163.483651634881, 5183.844484665466, 5212.426633070435, 5251.186066159751, 5258.07983602868, 4527.932081018787, 4580.1755683976535, 4585.679002469795, 4603.217453032992, 4628.861134165981, 4457.0, 4528.139653465897, 4578.35902973584, 4584.243599805995, 4641.234196357564, 5383.246345511631, 5427.511523962862, 5429.457054294539, 5452.836414907559, 5483.890322193533, 5157.252198431132, 5183.352080989876, 5184.222366645034, 5202.2196670499725, 5244.160934250502, 5225.085610018155, 5261.158214685632, 5318.0959236930585, 5400.0, 5417.431169727122, 5071.163258062378, 5079.415867175322, 5089.491050894881, 5097.311862898461, 5099.745012749274, 5216.165413533817, 5219.321488206509, 5221.530062294411, 5233.869153271046, 5235.240890070994, 4862.319275301531, 4871.805127794849, 4890.951090489049, 4942.036302920896, 5002.199648056294, 4972.254161875603, 4975.129352363249, 4981.078500477423, 5013.498650134958, 5078.822241221539, 4880.902381952412, 4883.780229889592, 4886.828960986349, 4907.288443175791, 4931.0, 4876.487968763371, 4936.136176169228, 4963.404391473046, 4986.127427700066, 5042.39491261051, 4849.175640141114, 4878.414590249193, 4898.044881248123, 4899.412070551557, 4931.778069986931], "value": 4741.913996754486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.2091110534407, 475.6361383541589, 475.69555484489933, 482.862384220497, 492.0405209840811, 30126.0, 40225.3966190507, 41187.0, 41231.17537649249, 41326.58673413265, 42318.788406058, 42683.573164268346, 42750.358744618854, 42763.78618106905, 42832.571674283245, 9244.260459163268, 9424.764380890498, 9429.43423394595, 9433.339666223366, 9437.433753974748, 9403.764905877373, 9421.434713917188, 9511.952440237788, 9524.190443812284, 9537.380070295443, 9480.620775168985, 9498.857517137249, 9519.428834269931, 9532.90467095327, 9539.379940303857, 9432.669856555021, 9510.667126650604, 9518.762030949212, 9524.428534287965, 9534.713958581287, 9400.529973501367, 9464.810703785955, 9476.95261523688, 9485.723426377688, 9486.810263794685, 39442.802785985885, 39794.60205397938, 39797.0, 39846.60153398487, 39914.201715965806, 3184.7292980096486, 3352.48036554332, 3538.982305088484, 3576.5708115026364, 4171.228322760268, 30552.694473055206, 31605.367892642018, 32014.0, 32110.839445802856, 32188.0, 3200.1199670090678, 3233.531137984979, 3246.788958717699, 3255.9837200813845, 3306.933861322784, 522334.0, 522641.0, 524564.0, 525068.0, 525456.9999999962, 51960.28436871941, 60990.390096099356, 61260.0, 61602.0, 61737.0, 51318.97362052738, 63658.68170659117, 64199.037014444824, 64241.35758642354, 68606.31393686098, 15800.288986995613, 15919.761203581955, 15961.760573591406, 15978.680426391407, 15988.840111598734, 11110.055645270242, 12369.814452783216, 12459.563915263103, 12465.626031219077, 12492.437840297027, 14379.424823007172, 14584.270786460847, 14644.169478815149, 16315.042194936683, 16596.336146554244, 6018.277806663228, 7101.786946391513, 7164.0, 7190.06529151207, 7236.190952261934, 664.6278084272823, 745.5787480073849, 872.9563521823883, 963.5642892090734, 1001.397182762443, 27341.86329068342, 28981.0, 29234.807389445876, 29546.556801647992, 30019.0, 927.638221093774, 951.5860600638745, 969.3214749675255, 1026.5329275179774, 1160.4255893332854, 14306.928465357607, 14747.262636868329, 14992.700145997238, 15062.878621268821, 15409.614759630875, 542407.0, 671945.0, 679701.0, 681266.0, 681772.0, 57967.71016144892, 58468.7076564623, 58487.70756146277, 58511.0, 58589.0, 1250.5373011985623, 1341.3092257487206, 1364.986350136486, 1396.4204854985067, 1398.678303990088, 22162.77837221607, 26549.60175597329, 28525.572116417854, 28785.424291513635, 28960.275993100342, 49937.75031124821, 50685.0, 50961.0, 51241.7437912808, 51270.0, 517659.8234017612, 519531.0, 520628.0, 520669.0, 520849.0, 22838.885805570866, 23240.535189296457, 25611.871940640176, 25706.8714656433, 25837.870810645825, 45554.77222613998, 46693.0, 47146.51362754737, 48231.517684822706, 48313.0, 38295.42556861705, 38511.32686084241, 38698.80650596729, 39117.0, 39229.41155882721, 38557.807210964886, 38949.278063569946, 39025.60974390333, 39312.01719957024, 39434.40848387334, 37691.62308376991, 37711.62288377081, 37755.0, 39119.41320880246, 40448.39327410032, 27402.45195096047, 33397.66602333946, 34482.24246839217, 34976.60093596228, 35092.0, 25026.74973250244, 25189.62215566731, 25285.87357063203, 25492.3142486197, 25829.483410332065], "value": 19487.181973518993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.49548200289576, 348.58866537485767, 363.2752658446399, 370.586795722769, 387.93017256893745, 14589.708205835877, 15255.389784408624, 17068.573285667848, 20546.0, 21541.353759387206, 20250.0, 20284.797152028474, 21585.56828863426, 21609.891950540266, 21651.0, 5474.835754927339, 6029.427204415595, 6029.668368239745, 6031.879362412749, 6035.396460353952, 4010.358342665174, 4235.0682849773, 5055.595552355821, 5745.913811292834, 5939.020061689837, 6603.240627327847, 6623.933760662404, 6653.567518111331, 6655.234648015492, 6670.266270710218, 6342.429181373675, 6668.066470694082, 6670.966645166744, 6671.466282697421, 6676.232233293184, 3646.5259516262718, 5409.9459005409835, 5756.338021127582, 5759.827205183851, 5799.101139323411, 26809.79355928988, 27368.315792105157, 27548.311292217655, 27921.0, 27996.300092497648, 1784.1996547354183, 2221.3447033125103, 2377.453185767266, 2562.9743702562923, 2619.9345016374555, 16753.581160470963, 16945.0, 17106.572335691708, 17242.482725518254, 17452.563685907826, 1712.2637265975625, 1846.9261229550802, 1931.7681878174708, 1993.252530301137, 2029.985007496252, 137916.0, 145601.9039333629, 147621.0, 149542.0, 149809.25095374454, 15128.39486420531, 20085.698714519294, 20710.825363336633, 20851.142538630404, 20885.89557052205, 33879.66120338815, 34391.828040860135, 34450.7942222021, 34515.482267766005, 34588.61645534201, 10412.5834966602, 10636.30863993838, 10683.786324273471, 10726.356418614909, 11247.887521124847, 8881.511516866505, 8882.555872206363, 8900.688475903442, 9003.729888103368, 9012.41419307743, 9228.60197220526, 9348.859767103499, 9464.574094165779, 9472.242220622335, 9479.431234125837, 2999.5950546676354, 3301.490866941142, 3502.527158833525, 3614.6137080642197, 3638.780343990164, 707.5719189890145, 755.8110472381904, 757.2162811490082, 799.9680012799423, 816.848882956655, 21034.47413814667, 21305.0, 22123.0, 22140.778592214192, 22418.88790556037, 788.7712563356562, 830.5276088264737, 847.7583888591722, 868.2099289646392, 889.088684098791, 10097.394156350496, 10917.344959302467, 11163.83254251187, 11191.608293709682, 11267.887321126684, 506415.0, 577685.3388796686, 581631.0, 587432.0, 592279.0, 129026.35486822505, 135649.0, 135827.0, 138831.0, 139025.0, 937.5546615357787, 948.1135138645388, 967.1585720423277, 967.9177269931994, 974.1476208317722, 17161.656766864842, 18406.72389914179, 18666.879987200813, 18760.906195468935, 18988.810111899256, 41833.0, 53766.19350709664, 59441.70279148577, 59732.50668733317, 59934.70032649809, 80330.0, 81164.59417702873, 81211.0, 81486.0, 82454.17545824705, 6951.478639101986, 7260.128784545888, 7268.0, 7365.8158546036775, 7956.2441568050945, 9109.498977556297, 13347.933260333637, 13380.397882095134, 13466.596002119952, 13506.729865402833, 11173.888261117285, 11250.324980501196, 11428.25716328436, 11610.071194304604, 11676.88323116758, 10679.839802403125, 11042.392668402994, 11209.71975700614, 11437.828432573684, 11491.482883270106, 11472.0, 11492.94253528727, 11663.533458661897, 11781.0, 11819.468123934272, 8106.837863242584, 8202.056763472277, 8321.750347489586, 8329.750107496784, 8336.208060234268, 10376.16990640762, 11367.20429569922, 11501.424928753697, 11681.532738690697, 11694.649160525198], "value": 10556.280210158253, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.6681996550774, 1503.7593984962405, 1637.402348142928, 1781.8396344329008, 1806.1420825108066, 8166.142555031718, 8446.662133514652, 8612.784680382985, 8625.137486251371, 8627.439216450926, 7562.432817538679, 7794.727184548542, 8179.304759095484, 8262.834743305146, 8414.747557573268, 1938.5638231397893, 2210.7899749523767, 2244.2145249162827, 2246.8764217968005, 2250.9437264068447, 2180.9563808723815, 2207.172310383606, 2264.3772962435296, 2266.150193677371, 2266.3880752196897, 1935.090507461491, 2028.7971202879667, 2128.936131916045, 2204.9779502205015, 2210.6794514795347, 2248.718910136233, 2263.2531264682616, 2269.4780200553964, 2270.6821045053707, 2270.8751018693965, 2216.8669879807103, 2242.9775702242932, 2246.6630005499305, 2247.213475283654, 2251.752307246202, 8552.315814734806, 8622.870656940151, 8629.00766411858, 8632.309415246766, 8646.870296945552, 4892.486288939681, 5077.746112694351, 5137.5101220645965, 5149.407818100929, 5157.149070403379, 3398.371301309241, 3413.6586341365664, 3452.7065199457825, 3513.5959364673135, 3533.0460775590705, 1054.4833031814433, 1059.7721489879714, 1075.1184029096144, 1081.1566977757361, 1085.137315833914, 11019.559217631366, 11300.773984520345, 11309.717257068556, 11516.25144365622, 11524.769504609945, 4162.791860406967, 4250.23495770758, 4340.696151269444, 4380.2772542530765, 4400.317950717644, 8985.595648195847, 8998.955005225063, 9007.819843603222, 9010.189082982562, 9012.053734357798, 5041.294218809369, 5045.344105266292, 5045.445001049881, 5047.0, 5047.646664733434, 4566.88582785433, 4709.128811169944, 4715.292706094045, 4718.41019872516, 4782.450018247877, 3402.1324562236473, 3407.3355695639607, 3410.5395771571016, 3456.6197718250974, 3508.824558772051, 861.7371701630938, 1042.6507120114695, 1049.5486940615456, 1052.2686732720686, 1052.594751020847, 572.6449601247253, 609.6646844235742, 775.2634996752994, 781.7732857471269, 816.9918300816915, 5460.5085542300585, 5521.199426083276, 5737.51231145349, 5913.76344946206, 6038.305594856648, 840.9537475438788, 869.0917990699734, 873.1879352202512, 883.1830556734994, 906.9682561110329, 3786.1291902861944, 3899.2396482685604, 3943.507061617298, 3946.8421263149726, 3960.2277555876904, 8902.955485222661, 10043.347182433119, 10506.684799456028, 10568.207384446197, 10574.312669676447, 10111.241656875764, 10513.0, 10530.104941080093, 10530.473476326153, 10541.578336866593, 7762.029746281715, 7819.100803407681, 7921.5247085176225, 7924.1283458820635, 7928.405369597303, 5049.394072711299, 5129.615278854027, 5590.692511911884, 5623.2408624835125, 5700.372958974593, 9316.48759318244, 9610.135087841849, 9636.662716804878, 9783.021697830445, 9844.950775246078, 9489.145976862115, 9909.65316213929, 10188.388696678223, 10188.592456301665, 10194.133498652402, 7223.711051558089, 7300.379467745302, 7411.888821667787, 7413.369863561657, 7422.925770742223, 7979.680812767424, 8007.879881801894, 8061.2744852963515, 8069.677212911418, 8093.31206847401, 6010.639361638316, 6637.767678131242, 6808.012838138541, 7199.3520583147765, 7453.0, 7088.468364872759, 7247.746328878675, 7283.344498995116, 7294.088238970129, 7442.627868606656, 6757.222919364336, 6771.052052712525, 6838.487113466411, 6847.0, 6873.931260687329, 4574.862754117382, 4610.492845786894, 4640.675152739276, 4666.696664716783, 4724.480307166277, 4903.583195428429, 4911.459739428588, 4967.950320496749, 5003.199488081889, 5048.697078175322], "value": 4349.142211581355, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.9446088247307, 288.8786709581975, 289.968768774733, 294.2643391521197, 296.6306947849927, 3166.3825554016976, 4136.67626359565, 4171.541130475649, 4175.728577642459, 4183.205191013709, 3618.945715814265, 3874.961250387495, 4142.668586513079, 4144.357624568197, 4150.584941505848, 2795.7838340321923, 3036.180231337543, 3066.9604712865917, 3154.9842250788715, 3186.8686616251243, 3207.4547326954475, 3268.1992911736656, 3278.1476816027885, 3281.7210537104374, 3295.1597342677705, 2784.540550809114, 3230.144011836865, 3239.271163988107, 3241.75686823488, 3287.934241315172, 3058.149819359289, 3108.818648913421, 3204.4071846708434, 3258.804471731704, 3260.8043517389033, 2876.590470669357, 2881.236472334833, 2882.495563276418, 2892.0022592205655, 2905.3462970831606, 3908.8045597720284, 3911.393733971239, 3923.5880232575455, 3929.98035009826, 3964.5638979712207, 3698.4822124902535, 3700.40793473043, 3719.163188282642, 3722.590515043343, 3740.9625903740884, 4115.567865374122, 4323.6757243206885, 4337.82648694055, 4360.514506731164, 4384.517703052694, 3194.9201269968203, 3216.4049650814673, 3236.1100697308193, 3237.983810080958, 3253.5760858160584, 4531.592156705912, 4538.978729785772, 4557.564367224347, 4575.084983003413, 4588.839390621346, 2720.238333266649, 3203.981491660286, 3274.5251938468787, 3283.7044665980175, 3287.539744435781, 2745.6567929008875, 3275.0349790125733, 3299.1916980339493, 3301.570795796579, 3315.353506066294, 2835.659720833472, 3227.8547465364113, 3309.569755931762, 3491.17957280039, 3721.3020560093714, 3750.393476533837, 3953.7430067046125, 4360.629346505582, 4389.517153113155, 4391.978040109779, 968.6173961285253, 1115.7949414632178, 1144.9084073274119, 1147.386148410613, 1149.8850114988434, 717.4690728860605, 717.6375930155239, 734.9563619660096, 738.6011553760909, 793.6666600028002, 3514.332276867387, 3778.3387907116694, 3897.493325867619, 3929.862454814068, 3964.682825373963, 4057.5739547347694, 4488.1472520221705, 4491.46102467706, 4495.415595972503, 4499.167653984024, 3277.360914621626, 3293.8517766700556, 4003.3594624860466, 4008.599140086026, 4015.3374693175297, 2162.653975363966, 2324.988375058114, 2348.4481146930457, 2360.350903501551, 2383.699729760789, 4646.514114948486, 4663.090697313991, 4664.113818374498, 4668.52941323482, 4697.929531057037, 3998.28030954431, 4458.821647134078, 4556.749378784199, 4646.907061858744, 4647.930281045787, 3490.598581163097, 3630.5280313560124, 4610.4467463903875, 4671.766411679402, 4675.322078298627, 4515.367848501278, 4546.954530454653, 4547.727136371828, 4553.453585569753, 4562.406887104655, 3894.82473288697, 4103.4870641169855, 4566.337881007169, 4571.1771881060395, 4576.954230457653, 4150.941509915073, 4237.131388065501, 4474.395956545824, 4545.931811022771, 4744.857654270378, 3154.495280755068, 3174.206448387903, 3198.696123868229, 3200.6639302872863, 3205.6313523945046, 3517.9296414071064, 4042.8477883802975, 4325.264705000221, 4488.349189367589, 4594.494605593449, 3791.526059242595, 4405.889852753707, 4449.644028477649, 4454.376387305715, 4473.060657262012, 3008.2737371599846, 4111.958880411157, 4334.306510958231, 4417.624501917373, 4455.888602784957, 3518.472229165646, 4236.597523235288, 4286.271333873186, 4296.968727505438, 4330.5236423993965, 4039.9496131005562, 4126.122614210467, 4395.7362558246605, 4431.158079964796, 4499.662525310549, 3486.825658717003, 3655.6892664123848, 4306.868100290376, 4314.892127696833, 4314.9784251078545], "value": 3247.2636822820077, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.9367356937875, 713.9393151582116, 728.2826415980259, 763.2062654838966, 794.3565711773467, 14552.56342309731, 15597.064176149424, 15610.98528595642, 15652.686946261068, 15690.529284121494, 12995.220286782776, 13095.27975961324, 13119.868801311984, 13179.802302965463, 13202.0, 685.1093578348152, 705.3933617089299, 860.9268212201955, 868.3357231717741, 883.8630012348065, 874.4359887872318, 878.1438097854573, 879.160401816264, 879.1779685993585, 879.2394578689419, 913.0595486648763, 929.1034152043277, 931.2317338195982, 931.804321092568, 934.8364036293656, 724.8550289942033, 915.4278575890069, 927.4064598656831, 928.9442633442015, 935.4808081514757, 668.993310066898, 669.243754557352, 671.3320246354859, 684.9212340580797, 687.089606271687, 15133.0, 15162.469313574082, 15182.544523664308, 15189.924050379677, 15203.923980380026, 14344.354504047344, 14700.926495367561, 14706.411743530245, 14740.484083057041, 15013.774793378108, 11609.245399049034, 11859.347735874524, 12032.097592680497, 12058.247128575309, 12069.637910862688, 3708.1100535871196, 3721.832517536717, 3908.1402091539817, 3926.7840268785208, 3937.9015524611827, 79571.0, 103567.4821625887, 104599.43100853493, 104763.85708428759, 105087.0, 11110.888891111146, 11217.549621236447, 11314.490847911864, 11392.94303528477, 11614.361210133524, 23399.298021059396, 23416.414589635395, 23428.18001369944, 23428.765712342658, 23433.41416464568, 7687.2697093776005, 8058.637361318754, 8179.427440079137, 8198.0, 8381.622826972802, 4382.298832186899, 4449.755263460476, 4470.418845550058, 4481.7983190756495, 4642.048380082075, 3405.6934875861293, 3535.6641119093647, 3555.8755443559353, 3558.1282585766135, 3558.4128618777613, 554.9223108764776, 560.938296787353, 563.1355868741463, 573.6156774960787, 575.3125015606394, 492.41648646354184, 548.7091841324104, 574.5317566183547, 579.3192998227066, 581.5463938128266, 8638.308935285162, 8651.783705407415, 8744.256738177199, 8807.691730789391, 9057.47286182998, 518.9299444575009, 547.663187139914, 569.1235497334059, 574.4456599381533, 585.2245774349004, 4154.937675934863, 4417.536158703354, 4813.78337974792, 5113.948860511422, 5119.10415677255, 87988.5600571993, 95739.0, 118802.0, 124941.0, 160430.0, 130205.0, 134248.0, 134664.0, 136647.0, 136953.89138325865, 97356.0, 97496.02503975011, 105027.0, 112236.0, 114543.2818507723, 7514.812129696801, 9606.759831004281, 9618.855717164388, 9750.707478775648, 9762.707118786448, 58280.83438331295, 60925.83572728025, 61012.69493652503, 61908.380916192065, 63256.36743632689, 89825.55087224784, 92225.69435763892, 92623.53688231515, 93688.0, 94375.0, 12707.237565746085, 13072.150310229805, 13209.801852972405, 13244.337783111, 13358.933205333911, 13958.23229722375, 14924.62688432798, 15079.849201507845, 15301.940157792973, 15805.0, 8050.59575806276, 12476.68808279764, 13157.144785588906, 14063.437462501386, 14208.218547979961, 13253.536126235536, 13515.189088654712, 13583.77746002825, 14151.787723184367, 14450.85549144495, 13574.389152488358, 13723.725525489634, 13828.446862125402, 13927.58217253484, 14205.28973551339, 11210.775784484427, 11273.71815704614, 11300.835765985537, 11367.20429569922, 11504.137189710973, 10824.0, 11225.831612525983, 11271.211015229183, 11429.828552571884, 11433.371164585695], "value": 7686.739128951818, "unit": "score"}], "name": "dromaeo_css", "value": 6878.44916172989, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 115.0, 115.0, 116.0, 117.0, 117.0, 115.0, 116.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 112.0, 171.0, 124.0, 112.0, 137.0, 106.0, 151.0, 111.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 214.0, 212.0, 213.0, 212.0, 248.0, 237.0, 211.0, 210.0, 208.0], "value": 218.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 60.0, 91.0, 61.0, 59.0, 66.0, 64.0, 85.0, 58.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 91.0, 96.0, 74.0, 78.0, 101.0, 133.0, 86.0, 70.0, 103.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 88.0, 92.0, 89.0, 92.0, 88.0, 91.0, 89.0, 92.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 98.0, 97.0, 138.0, 157.0, 98.0, 97.0, 97.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 107.0, 102.0, 127.0, 165.0, 160.0, 101.0, 106.0, 106.0, 106.0], "value": 118.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 54.0, 53.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 83.0, 80.0, 80.0, 78.0, 79.0, 77.0, 80.0, 78.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 87.0, 85.0, 87.0, 85.0, 87.0, 85.0, 88.0, 89.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 151.0, 154.0, 154.0, 150.0, 141.0, 143.0, 143.0, 179.0, 142.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 175.0, 238.0, 202.0, 171.0, 172.0, 177.0, 170.0, 178.0, 172.0], "value": 182.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 70.0, 68.0, 69.0, 68.0, 67.0, 67.0, 69.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1560.6999999999998, "alertThreshold": 2.0}]} 13:07:09 INFO - Return code: 0 13:07:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:07:09 INFO - # TBPL SUCCESS # 13:07:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:07:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:07:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:07:09 INFO - 2016-07-19 13:07:09.967 screenresolution[1663:9680] starting screenresolution argv=screenresolution get 13:07:09 INFO - 2016-07-19 13:07:09.970 screenresolution[1663:9680] Display 0: 1600x1200x32@60 13:07:09 INFO - 2016-07-19 13:07:09.977 screenresolution[1664:9683] starting screenresolution argv=screenresolution list 13:07:09 INFO - Available Modes on Display 0 13:07:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:07:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:07:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:07:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:07:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:07:10 INFO - Intel Iris: 13:07:10 INFO - Chipset Model: Intel Iris 13:07:10 INFO - Type: GPU 13:07:10 INFO - Bus: Built-In 13:07:10 INFO - VRAM (Dynamic, Max): 1536 MB 13:07:10 INFO - Vendor: Intel (0x8086) 13:07:10 INFO - Device ID: 0x0a2e 13:07:10 INFO - Revision ID: 0x0009 13:07:10 INFO - Displays: 13:07:10 INFO - Display: 13:07:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:07:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:07:10 INFO - Display Serial Number: 1600x1200 60 13:07:10 INFO - Main Display: Yes 13:07:10 INFO - Mirror: Off 13:07:10 INFO - Online: Yes 13:07:10 INFO - Rotation: Supported 13:07:10 INFO - Return code: 0 13:07:10 INFO - Running post-action listener: _resource_record_post_action 13:07:10 INFO - ##### 13:07:10 INFO - ##### Finished run-tests step (success) 13:07:10 INFO - ##### 13:07:10 INFO - Running post-run listener: _resource_record_post_run 13:07:10 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37254656; Write bytes: 703940096; Read time: 434; Write time: 2350 13:07:10 INFO - TinderboxPrint: CPU usage
53.6% 13:07:10 INFO - TinderboxPrint: I/O read bytes / time
37,254,656 / 434 13:07:10 INFO - TinderboxPrint: I/O write bytes / time
703,940,096 / 2,350 13:07:10 INFO - TinderboxPrint: CPU idle
2,251.5 (46.4%) 13:07:10 INFO - TinderboxPrint: CPU system
969.9 (20.0%) 13:07:10 INFO - TinderboxPrint: CPU user
1,628.2 (33.6%) 13:07:10 INFO - TinderboxPrint: Swap in / out
444,817,408 / 0 13:07:10 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 407785984; Write bytes: 402212352; Read time: 31781; Write time: 731 13:07:10 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34473472; Write bytes: 292048896; Read time: 406; Write time: 1591 13:07:10 INFO - Running post-run listener: _upload_blobber_files 13:07:10 INFO - Blob upload gear active. 13:07:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:07:10 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 13:07:10 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:07:10 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:07:11 INFO - (blobuploader) - INFO - Open directory for files ... 13:07:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:07:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:07:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:12 INFO - (blobuploader) - INFO - Done attempting. 13:07:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:07:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:13 INFO - (blobuploader) - INFO - Done attempting. 13:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:07:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:07:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:19 INFO - (blobuploader) - INFO - Done attempting. 13:07:19 INFO - (blobuploader) - INFO - Iteration through files over. 13:07:19 INFO - Return code: 0 13:07:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:07:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:07:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab751fd828291fe38a74b4a501e60f4dc8edbbf677a00329007f0d31bb18430f6e04b29695735d42b797b682fa9abfc525a507a1e39fa3b4fca0ed19259512d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2a374685acadefe1c26db9e56805249d65c3fbd1d1ac067f40eb44433edbcdcd1bdaa67ca968ef4353b094eb10ab9db6239c93a2bbd58cd9a59aefedf92ab13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a126c8a686738dc4516dc401c3307b9a17c01bd3b544022e30285f74d15ad1ab2fe0c74ef829ff2b633eafaf38099541ad6bf465336c51a8f9d354bca67eeb0"} 13:07:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:07:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:07:19 INFO - Contents: 13:07:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab751fd828291fe38a74b4a501e60f4dc8edbbf677a00329007f0d31bb18430f6e04b29695735d42b797b682fa9abfc525a507a1e39fa3b4fca0ed19259512d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2a374685acadefe1c26db9e56805249d65c3fbd1d1ac067f40eb44433edbcdcd1bdaa67ca968ef4353b094eb10ab9db6239c93a2bbd58cd9a59aefedf92ab13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a126c8a686738dc4516dc401c3307b9a17c01bd3b544022e30285f74d15ad1ab2fe0c74ef829ff2b633eafaf38099541ad6bf465336c51a8f9d354bca67eeb0"} 13:07:19 INFO - Running post-run listener: copy_logs_to_upload_dir 13:07:19 INFO - Copying logs to upload dir... 13:07:19 INFO - mkdir: /builds/slave/test/build/upload/logs 13:07:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.637308 ========= master_lag: 1.88 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-07-19 13:07:21.073410) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 13:07:21.078089) ========= 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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/Fx-Team/sha512/ab751fd828291fe38a74b4a501e60f4dc8edbbf677a00329007f0d31bb18430f6e04b29695735d42b797b682fa9abfc525a507a1e39fa3b4fca0ed19259512d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2a374685acadefe1c26db9e56805249d65c3fbd1d1ac067f40eb44433edbcdcd1bdaa67ca968ef4353b094eb10ab9db6239c93a2bbd58cd9a59aefedf92ab13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a126c8a686738dc4516dc401c3307b9a17c01bd3b544022e30285f74d15ad1ab2fe0c74ef829ff2b633eafaf38099541ad6bf465336c51a8f9d354bca67eeb0"} build_url:https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012746 build_url: 'https://queue.taskcluster.net/v1/task/Stn90HOlSzy0wRzU0E9sZA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab751fd828291fe38a74b4a501e60f4dc8edbbf677a00329007f0d31bb18430f6e04b29695735d42b797b682fa9abfc525a507a1e39fa3b4fca0ed19259512d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c2a374685acadefe1c26db9e56805249d65c3fbd1d1ac067f40eb44433edbcdcd1bdaa67ca968ef4353b094eb10ab9db6239c93a2bbd58cd9a59aefedf92ab13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a126c8a686738dc4516dc401c3307b9a17c01bd3b544022e30285f74d15ad1ab2fe0c74ef829ff2b633eafaf38099541ad6bf465336c51a8f9d354bca67eeb0"}' ========= master_lag: 0.74 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 13:07:21.830752) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:07:21.831080) ========= 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.qz1x8ZB49F/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.7vtRJlGaIr/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005373 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 13:07:21.858917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 13:07:21.859219) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 13:07:21.859515) ========= ========= Total master_lag: 2.72 =========