builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0216 starttime: 1464153189.16 results: success (0) buildid: 20160524211736 builduid: 99f089bccea44beb85aa5d1bb4f26ca4 revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.162339) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.162749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.163034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.236371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.236692) ========= 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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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-05-24 22:13:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.5M=0.001s 2016-05-24 22:13:09 (19.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128932 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.409850) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.410125) ========= 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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.025129 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:09.471779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 22:13:09.472104) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 242c458c4c61e94c8d12628612ef5e4e83992eaa --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 242c458c4c61e94c8d12628612ef5e4e83992eaa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4f3uAius1a/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.ZC1Vqg7UJV/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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-05-24 22:13:09,555 truncating revision to first 12 chars 2016-05-24 22:13:09,555 Setting DEBUG logging. 2016-05-24 22:13:09,555 attempt 1/10 2016-05-24 22:13:09,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/242c458c4c61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 22:13:11,254 unpacking tar archive at: fx-team-242c458c4c61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.952134 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 22:13:11.444868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:11.445178) ========= script_repo_revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:11.445584) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:11.445852) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 22:13:11.473651) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-24 22:13:11.473959) ========= /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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:13:11 INFO - MultiFileLogger online at 20160524 22:13:11 in /builds/slave/test 22:13:11 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 22:13:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:13:11 INFO - {'append_to_log': False, 22:13:11 INFO - 'base_work_dir': '/builds/slave/test', 22:13:11 INFO - 'blob_upload_branch': 'Fx-Team', 22:13:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:13:11 INFO - 'branch': 'Fx-Team', 22:13:11 INFO - 'buildbot_json_path': 'buildprops.json', 22:13:11 INFO - 'config_files': ('talos/mac_config.py',), 22:13:11 INFO - 'default_actions': ('clobber', 22:13:11 INFO - 'read-buildbot-config', 22:13:11 INFO - 'download-and-extract', 22:13:11 INFO - 'populate-webroot', 22:13:11 INFO - 'create-virtualenv', 22:13:11 INFO - 'install', 22:13:11 INFO - 'run-tests'), 22:13:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:13:11 INFO - 'download_minidump_stackwalk': True, 22:13:11 INFO - 'download_symbols': 'ondemand', 22:13:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:13:11 INFO - 'tooltool.py': '/tools/tooltool.py', 22:13:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:13:11 INFO - '/tools/misc-python/virtualenv.py')}, 22:13:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:13:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:13:11 INFO - 'installer_path': 'installer.exe', 22:13:11 INFO - 'log_level': 'info', 22:13:11 INFO - 'log_name': 'talos', 22:13:11 INFO - 'log_to_console': True, 22:13:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:13:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:13:11 INFO - 'opt_config_files': (), 22:13:11 INFO - 'pip_index': False, 22:13:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:13:11 INFO - 'cmd': ('bash', 22:13:11 INFO - '-c', 22:13:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:13:11 INFO - 'enabled': True, 22:13:11 INFO - 'halt_on_failure': False, 22:13:11 INFO - 'name': 'check_screen_resolution'},), 22:13:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:13:11 INFO - 'cmd': ('bash', 22:13:11 INFO - '-c', 22:13:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:13:11 INFO - 'enabled': True, 22:13:11 INFO - 'halt_on_failure': False, 22:13:11 INFO - 'name': 'check_screen_resolution'},), 22:13:11 INFO - 'run_cmd_checks_enabled': True, 22:13:11 INFO - 'sps_profile': False, 22:13:11 INFO - 'sps_profile_interval': 0, 22:13:11 INFO - 'suite': 'dromaeojs', 22:13:11 INFO - 'system_bits': '32', 22:13:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:13:11 INFO - 'title': 't-yosemite-r7-0216', 22:13:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:13:11 INFO - 'use_talos_json': True, 22:13:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:13:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:13:11 INFO - 'work_dir': 'build'} 22:13:11 INFO - ##### 22:13:11 INFO - ##### Running clobber step. 22:13:11 INFO - ##### 22:13:11 INFO - Running pre-action listener: _resource_record_pre_action 22:13:11 INFO - Running main action method: clobber 22:13:11 INFO - rmtree: /builds/slave/test/build 22:13:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:13:13 INFO - Running post-action listener: _resource_record_post_action 22:13:13 INFO - ##### 22:13:13 INFO - ##### Finished clobber step (success) 22:13:13 INFO - ##### 22:13:13 INFO - ##### 22:13:13 INFO - ##### Running read-buildbot-config step. 22:13:13 INFO - ##### 22:13:13 INFO - Running pre-action listener: _resource_record_pre_action 22:13:13 INFO - Running main action method: read_buildbot_config 22:13:13 INFO - Using buildbot properties: 22:13:13 INFO - { 22:13:13 INFO - "project": "", 22:13:13 INFO - "product": "firefox", 22:13:13 INFO - "script_repo_revision": "production", 22:13:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:13:13 INFO - "repository": "", 22:13:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:13:13 INFO - "buildid": "20160524211736", 22:13:13 INFO - "pgo_build": "False", 22:13:13 INFO - "basedir": "/builds/slave/test", 22:13:13 INFO - "buildnumber": 29, 22:13:13 INFO - "slavename": "t-yosemite-r7-0216", 22:13:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 22:13:13 INFO - "platform": "yosemite_r7", 22:13:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:13:13 INFO - "branch": "fx-team", 22:13:13 INFO - "repo_path": "integration/fx-team", 22:13:13 INFO - "revision": "242c458c4c61e94c8d12628612ef5e4e83992eaa", 22:13:13 INFO - "stage_platform": "macosx64", 22:13:13 INFO - "builduid": "99f089bccea44beb85aa5d1bb4f26ca4", 22:13:13 INFO - "slavebuilddir": "test" 22:13:13 INFO - } 22:13:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:13:13 INFO - Running post-action listener: _resource_record_post_action 22:13:13 INFO - ##### 22:13:13 INFO - ##### Finished read-buildbot-config step (success) 22:13:13 INFO - ##### 22:13:13 INFO - ##### 22:13:13 INFO - ##### Running download-and-extract step. 22:13:13 INFO - ##### 22:13:13 INFO - Running pre-action listener: _resource_record_pre_action 22:13:13 INFO - Running main action method: download_and_extract 22:13:13 INFO - mkdir: /builds/slave/test/build/tests 22:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:13 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:13:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:13:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:13:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:13:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 22:13:16 INFO - Downloaded 1199 bytes. 22:13:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:13:16 INFO - Using the following test package requirements: 22:13:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:13:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:13:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:13:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'jsshell-mac.zip'], 22:13:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:13:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:13:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:13:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:13:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:13:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:13:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:13:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:16 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:13:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:13:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:13:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:13:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:13:20 INFO - Downloaded 18632983 bytes. 22:13:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:13:23 INFO - Return code: 0 22:13:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:23 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:13:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:13:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:13:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:13:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:13:27 INFO - Downloaded 18632983 bytes. 22:13:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:13:30 INFO - Return code: 0 22:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:30 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:13:31 INFO - Downloaded 10840359 bytes. 22:13:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:13:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:13:32 INFO - Return code: 0 22:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:32 INFO - https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:13:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:13:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:13:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:13:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 22:13:36 INFO - Downloaded 110240957 bytes. 22:13:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:13:36 INFO - mkdir: /builds/slave/test/properties 22:13:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:13:36 INFO - Writing to file /builds/slave/test/properties/build_url 22:13:36 INFO - Contents: 22:13:36 INFO - build_url:https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:13:37 INFO - Running post-action listener: _resource_record_post_action 22:13:37 INFO - Running post-action listener: set_extra_try_arguments 22:13:37 INFO - ##### 22:13:37 INFO - ##### Finished download-and-extract step (success) 22:13:37 INFO - ##### 22:13:37 INFO - ##### 22:13:37 INFO - ##### Running populate-webroot step. 22:13:37 INFO - ##### 22:13:37 INFO - Running pre-action listener: _resource_record_pre_action 22:13:37 INFO - Running main action method: populate_webroot 22:13:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:13:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:13:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:13:37 INFO - '--tppagecycles', 22:13:37 INFO - '7'], 22:13:37 INFO - 'tests': ['tsvgm']}}, 22:13:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:13:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:13:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:13:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:13:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:13:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['damp', 'tps']}, 22:13:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'tests': ['damp', 'tps']}, 22:13:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['dromaeo_dom']}, 22:13:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:13:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['a11yr', 22:13:37 INFO - 'ts_paint', 22:13:37 INFO - 'tpaint', 22:13:37 INFO - 'sessionrestore', 22:13:37 INFO - 'sessionrestore_no_auto_restore', 22:13:37 INFO - 'tabpaint']}, 22:13:37 INFO - 'other-e10s': {'tests': ['a11yr', 22:13:37 INFO - 'ts_paint', 22:13:37 INFO - 'tpaint', 22:13:37 INFO - 'sessionrestore', 22:13:37 INFO - 'sessionrestore_no_auto_restore', 22:13:37 INFO - 'tabpaint']}, 22:13:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:13:37 INFO - 'ts_paint', 22:13:37 INFO - 'tpaint', 22:13:37 INFO - 'sessionrestore', 22:13:37 INFO - 'sessionrestore_no_auto_restore', 22:13:37 INFO - 'tabpaint']}, 22:13:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:13:37 INFO - 'ts_paint', 22:13:37 INFO - 'tpaint', 22:13:37 INFO - 'sessionrestore', 22:13:37 INFO - 'sessionrestore_no_auto_restore', 22:13:37 INFO - 'tabpaint']}, 22:13:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['a11yr', 22:13:37 INFO - 'ts_paint', 22:13:37 INFO - 'tpaint', 22:13:37 INFO - 'sessionrestore', 22:13:37 INFO - 'sessionrestore_no_auto_restore', 22:13:37 INFO - 'tabpaint']}, 22:13:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['a11yr', 22:13:37 INFO - 'ts_paint', 22:13:37 INFO - 'tpaint', 22:13:37 INFO - 'sessionrestore', 22:13:37 INFO - 'sessionrestore_no_auto_restore', 22:13:37 INFO - 'tabpaint']}, 22:13:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['tsvgx', 22:13:37 INFO - 'tsvgr_opacity', 22:13:37 INFO - 'tart', 22:13:37 INFO - 'tscrollx', 22:13:37 INFO - 'cart']}, 22:13:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:13:37 INFO - 'tsvgr_opacity', 22:13:37 INFO - 'tart', 22:13:37 INFO - 'tscrollx', 22:13:37 INFO - 'cart']}, 22:13:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'talos_options': ['--disable-e10s'], 22:13:37 INFO - 'tests': ['tp5o']}, 22:13:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'tests': ['tp5o']}, 22:13:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'talos_options': ['--disable-e10s', 22:13:37 INFO - '--xperf_path', 22:13:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:37 INFO - 'tests': ['tp5n']}, 22:13:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:13:37 INFO - 'talos_options': ['--xperf_path', 22:13:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:13:37 INFO - 'tests': ['tp5n']}}, 22:13:37 INFO - 'talos.zip': {'path': '', 22:13:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:13:37 INFO - Running post-action listener: _resource_record_post_action 22:13:37 INFO - ##### 22:13:37 INFO - ##### Finished populate-webroot step (success) 22:13:37 INFO - ##### 22:13:37 INFO - ##### 22:13:37 INFO - ##### Running create-virtualenv step. 22:13:37 INFO - ##### 22:13:37 INFO - Running pre-action listener: _resource_record_pre_action 22:13:37 INFO - Running main action method: create_virtualenv 22:13:37 INFO - Creating virtualenv /builds/slave/test/build/venv 22:13:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:13:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:13:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:13:37 INFO - Using real prefix '/tools/python27' 22:13:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:13:38 INFO - Installing distribute.............................................................................................................................................................................................done. 22:13:41 INFO - Installing pip.................done. 22:13:41 INFO - Return code: 0 22:13:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:41 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:13:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:13:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:41 INFO - 'HOME': '/Users/cltbld', 22:13:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:41 INFO - 'LOGNAME': 'cltbld', 22:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:41 INFO - 'MOZ_NO_REMOTE': '1', 22:13:41 INFO - 'NO_EM_RESTART': '1', 22:13:41 INFO - 'PAGER': '/bin/cat', 22:13:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:41 INFO - 'PWD': '/builds/slave/test', 22:13:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:41 INFO - 'SHELL': '/bin/bash', 22:13:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:13:41 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:13:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:41 INFO - 'USER': 'cltbld', 22:13:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:41 INFO - 'XPC_FLAGS': '0x0', 22:13:41 INFO - 'XPC_SERVICE_NAME': '0', 22:13:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:13:41 INFO - Cleaning up... 22:13:41 INFO - Return code: 0 22:13:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:41 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:13:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:13:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:41 INFO - 'HOME': '/Users/cltbld', 22:13:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:41 INFO - 'LOGNAME': 'cltbld', 22:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:41 INFO - 'MOZ_NO_REMOTE': '1', 22:13:41 INFO - 'NO_EM_RESTART': '1', 22:13:41 INFO - 'PAGER': '/bin/cat', 22:13:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:41 INFO - 'PWD': '/builds/slave/test', 22:13:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:41 INFO - 'SHELL': '/bin/bash', 22:13:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:13:41 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:13:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:41 INFO - 'USER': 'cltbld', 22:13:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:41 INFO - 'XPC_FLAGS': '0x0', 22:13:41 INFO - 'XPC_SERVICE_NAME': '0', 22:13:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:41 INFO - Downloading/unpacking psutil>=3.1.1 22:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:13:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:44 INFO - Installing collected packages: psutil 22:13:44 INFO - Running setup.py install for psutil 22:13:44 INFO - building 'psutil._psutil_osx' extension 22:13:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:13:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:13:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:13:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:13:45 INFO - building 'psutil._psutil_posix' extension 22:13:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:13:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:13:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:13:45 INFO - ^ 22:13:45 INFO - 1 warning generated. 22:13:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:13:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:13:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:13:45 INFO - Successfully installed psutil 22:13:45 INFO - Cleaning up... 22:13:45 INFO - Return code: 0 22:13:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:45 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:45 INFO - 'HOME': '/Users/cltbld', 22:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:45 INFO - 'LOGNAME': 'cltbld', 22:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:45 INFO - 'MOZ_NO_REMOTE': '1', 22:13:45 INFO - 'NO_EM_RESTART': '1', 22:13:45 INFO - 'PAGER': '/bin/cat', 22:13:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:45 INFO - 'PWD': '/builds/slave/test', 22:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:45 INFO - 'SHELL': '/bin/bash', 22:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:13:45 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:45 INFO - 'USER': 'cltbld', 22:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:45 INFO - 'XPC_FLAGS': '0x0', 22:13:45 INFO - 'XPC_SERVICE_NAME': '0', 22:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:13:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:13:48 INFO - Installing collected packages: mozsystemmonitor 22:13:48 INFO - Running setup.py install for mozsystemmonitor 22:13:48 INFO - Successfully installed mozsystemmonitor 22:13:48 INFO - Cleaning up... 22:13:48 INFO - Return code: 0 22:13:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:48 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:48 INFO - 'HOME': '/Users/cltbld', 22:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:48 INFO - 'LOGNAME': 'cltbld', 22:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:48 INFO - 'MOZ_NO_REMOTE': '1', 22:13:48 INFO - 'NO_EM_RESTART': '1', 22:13:48 INFO - 'PAGER': '/bin/cat', 22:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:48 INFO - 'PWD': '/builds/slave/test', 22:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:48 INFO - 'SHELL': '/bin/bash', 22:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:13:48 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:48 INFO - 'USER': 'cltbld', 22:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:48 INFO - 'XPC_FLAGS': '0x0', 22:13:48 INFO - 'XPC_SERVICE_NAME': '0', 22:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:48 INFO - Downloading/unpacking blobuploader==1.2.4 22:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:51 INFO - Downloading blobuploader-1.2.4.tar.gz 22:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:13:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:13:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:13:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:13:52 INFO - Downloading docopt-0.6.1.tar.gz 22:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:13:52 INFO - Installing collected packages: blobuploader, requests, docopt 22:13:52 INFO - Running setup.py install for blobuploader 22:13:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:13:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:13:52 INFO - Running setup.py install for requests 22:13:53 INFO - Running setup.py install for docopt 22:13:53 INFO - Successfully installed blobuploader requests docopt 22:13:53 INFO - Cleaning up... 22:13:53 INFO - Return code: 0 22:13:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:53 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:13:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:13:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:53 INFO - 'HOME': '/Users/cltbld', 22:13:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:53 INFO - 'LOGNAME': 'cltbld', 22:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:53 INFO - 'MOZ_NO_REMOTE': '1', 22:13:53 INFO - 'NO_EM_RESTART': '1', 22:13:53 INFO - 'PAGER': '/bin/cat', 22:13:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:53 INFO - 'PWD': '/builds/slave/test', 22:13:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:53 INFO - 'SHELL': '/bin/bash', 22:13:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:13:53 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:13:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:53 INFO - 'USER': 'cltbld', 22:13:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:53 INFO - 'XPC_FLAGS': '0x0', 22:13:53 INFO - 'XPC_SERVICE_NAME': '0', 22:13:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:13:53 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Hh8Z2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:13:53 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-ePLZD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-YQAWXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-EYCg9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-dBPiEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-JAdOfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-aMpxxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-4lVt1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-XRAiNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-eXvTLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-rVclr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:13:54 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-4LzBCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:13:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-A4RTKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:13:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-5fvdFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-pdq90i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:13:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-qzps6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:13:55 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-iHO8EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:13:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:13:55 INFO - Running setup.py install for manifestparser 22:13:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:13:55 INFO - Running setup.py install for mozcrash 22:13:55 INFO - Running setup.py install for mozdebug 22:13:55 INFO - Running setup.py install for mozdevice 22:13:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Running setup.py install for mozfile 22:13:56 INFO - Running setup.py install for mozhttpd 22:13:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Running setup.py install for mozinfo 22:13:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Running setup.py install for mozInstall 22:13:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:13:56 INFO - Running setup.py install for mozleak 22:13:56 INFO - Running setup.py install for mozlog 22:13:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:13:57 INFO - Running setup.py install for moznetwork 22:13:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:13:57 INFO - Running setup.py install for mozprocess 22:13:57 INFO - Running setup.py install for mozprofile 22:13:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:13:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:13:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:13:57 INFO - Running setup.py install for mozrunner 22:13:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:13:57 INFO - Running setup.py install for mozscreenshot 22:13:57 INFO - Running setup.py install for moztest 22:13:57 INFO - Running setup.py install for mozversion 22:13:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:13:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:13:58 INFO - Cleaning up... 22:13:58 INFO - Return code: 0 22:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:13:58 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:13:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:13:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:13:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:13:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:13:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:13:58 INFO - 'HOME': '/Users/cltbld', 22:13:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:13:58 INFO - 'LOGNAME': 'cltbld', 22:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:13:58 INFO - 'MOZ_NO_REMOTE': '1', 22:13:58 INFO - 'NO_EM_RESTART': '1', 22:13:58 INFO - 'PAGER': '/bin/cat', 22:13:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:13:58 INFO - 'PWD': '/builds/slave/test', 22:13:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:13:58 INFO - 'SHELL': '/bin/bash', 22:13:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:13:58 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:13:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:13:58 INFO - 'USER': 'cltbld', 22:13:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:13:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:13:58 INFO - 'XPC_FLAGS': '0x0', 22:13:58 INFO - 'XPC_SERVICE_NAME': '0', 22:13:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:13:58 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-8WxL1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:13:58 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-FQbDKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:13:58 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-5T8Vfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:13:58 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-SMSF0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:13:58 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-ExQzPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:13:58 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-m1Nn8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-tYTwkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-0x63Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-DF1_bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-MBcqoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:13:59 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)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-GplbBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-T_JyyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-diFT43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-zIF8s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-TRwVDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:13:59 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Jz5TQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:14:00 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-xYOnf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:14:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:02 INFO - Downloading blessings-1.6.tar.gz 22:14:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:14:02 INFO - Installing collected packages: blessings 22:14:02 INFO - Running setup.py install for blessings 22:14:02 INFO - Successfully installed blessings 22:14:02 INFO - Cleaning up... 22:14:02 INFO - Return code: 0 22:14:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:14:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:14:03 INFO - Reading from file tmpfile_stdout 22:14:03 INFO - Current package versions: 22:14:03 INFO - blessings == 1.6 22:14:03 INFO - blobuploader == 1.2.4 22:14:03 INFO - docopt == 0.6.1 22:14:03 INFO - manifestparser == 1.1 22:14:03 INFO - mozInstall == 1.12 22:14:03 INFO - mozcrash == 0.17 22:14:03 INFO - mozdebug == 0.1 22:14:03 INFO - mozdevice == 0.48 22:14:03 INFO - mozfile == 1.2 22:14:03 INFO - mozhttpd == 0.7 22:14:03 INFO - mozinfo == 0.9 22:14:03 INFO - mozleak == 0.1 22:14:03 INFO - mozlog == 3.2 22:14:03 INFO - moznetwork == 0.27 22:14:03 INFO - mozprocess == 0.22 22:14:03 INFO - mozprofile == 0.28 22:14:03 INFO - mozrunner == 6.11 22:14:03 INFO - mozscreenshot == 0.1 22:14:03 INFO - mozsystemmonitor == 0.3 22:14:03 INFO - moztest == 0.7 22:14:03 INFO - mozversion == 1.4 22:14:03 INFO - psutil == 3.1.1 22:14:03 INFO - requests == 1.2.3 22:14:03 INFO - wsgiref == 0.1.2 22:14:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:03 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:14:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:14:03 INFO - 'HOME': '/Users/cltbld', 22:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:14:03 INFO - 'LOGNAME': 'cltbld', 22:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:03 INFO - 'MOZ_NO_REMOTE': '1', 22:14:03 INFO - 'NO_EM_RESTART': '1', 22:14:03 INFO - 'PAGER': '/bin/cat', 22:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:03 INFO - 'PWD': '/builds/slave/test', 22:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:14:03 INFO - 'SHELL': '/bin/bash', 22:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:14:03 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:14:03 INFO - 'USER': 'cltbld', 22:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:03 INFO - 'XPC_FLAGS': '0x0', 22:14:03 INFO - 'XPC_SERVICE_NAME': '0', 22:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:14:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:14:03 INFO - Cleaning up... 22:14:03 INFO - Return code: 0 22:14:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:03 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 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:14:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:14:03 INFO - 'HOME': '/Users/cltbld', 22:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:14:03 INFO - 'LOGNAME': 'cltbld', 22:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:03 INFO - 'MOZ_NO_REMOTE': '1', 22:14:03 INFO - 'NO_EM_RESTART': '1', 22:14:03 INFO - 'PAGER': '/bin/cat', 22:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:03 INFO - 'PWD': '/builds/slave/test', 22:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:14:03 INFO - 'SHELL': '/bin/bash', 22:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:14:03 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:14:03 INFO - 'USER': 'cltbld', 22:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:03 INFO - 'XPC_FLAGS': '0x0', 22:14:03 INFO - 'XPC_SERVICE_NAME': '0', 22:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:14:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:03 INFO - Downloading/unpacking jsonschema 22:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:14:06 INFO - Downloading/unpacking functools32 (from jsonschema) 22:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:06 INFO - Downloading functools32-3.2.3-2.tar.gz 22:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:14:06 INFO - warning: no files found matching '*.txt' 22:14:06 INFO - no previously-included directories found matching 'build' 22:14:06 INFO - no previously-included directories found matching 'dist' 22:14:06 INFO - no previously-included directories found matching '.git*' 22:14:06 INFO - Installing collected packages: jsonschema, functools32 22:14:06 INFO - Running setup.py install for functools32 22:14:06 INFO - warning: no files found matching '*.txt' 22:14:06 INFO - no previously-included directories found matching 'build' 22:14:06 INFO - no previously-included directories found matching 'dist' 22:14:06 INFO - no previously-included directories found matching '.git*' 22:14:06 INFO - Successfully installed jsonschema functools32 22:14:06 INFO - Cleaning up... 22:14:06 INFO - Return code: 0 22:14:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:14:07 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a368a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a36e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c50c57d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, '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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:14:07 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', 'flake8'] in /builds/slave/test/build 22:14:07 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 flake8 22:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:14:07 INFO - 'HOME': '/Users/cltbld', 22:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:14:07 INFO - 'LOGNAME': 'cltbld', 22:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:07 INFO - 'MOZ_NO_REMOTE': '1', 22:14:07 INFO - 'NO_EM_RESTART': '1', 22:14:07 INFO - 'PAGER': '/bin/cat', 22:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:07 INFO - 'PWD': '/builds/slave/test', 22:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:14:07 INFO - 'SHELL': '/bin/bash', 22:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:14:07 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:14:07 INFO - 'USER': 'cltbld', 22:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:07 INFO - 'XPC_FLAGS': '0x0', 22:14:07 INFO - 'XPC_SERVICE_NAME': '0', 22:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:14:07 INFO - Downloading/unpacking flake8 22:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:14:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:14:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:14:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:14:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:14:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:14:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:14:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:14:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:14:10 INFO - no previously-included directories found matching 'docs/_build' 22:14:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:14:10 INFO - Running setup.py install for pep8 22:14:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:14:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:14:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:14:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:14:11 INFO - no previously-included directories found matching 'docs/_build' 22:14:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:14:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:14:11 INFO - Cleaning up... 22:14:11 INFO - Return code: 0 22:14:11 INFO - Running post-action listener: _resource_record_post_action 22:14:11 INFO - Running post-action listener: _start_resource_monitoring 22:14:11 INFO - Starting resource monitoring. 22:14:11 INFO - ##### 22:14:11 INFO - ##### Finished create-virtualenv step (success) 22:14:11 INFO - ##### 22:14:11 INFO - ##### 22:14:11 INFO - ##### Running install step. 22:14:11 INFO - ##### 22:14:11 INFO - Running pre-action listener: _resource_record_pre_action 22:14:11 INFO - Running main action method: install 22:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:14:11 INFO - Reading from file tmpfile_stdout 22:14:11 INFO - Detecting whether we're running mozinstall >=1.0... 22:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:14:11 INFO - Reading from file tmpfile_stdout 22:14:11 INFO - Output received: 22:14:11 INFO - Usage: mozinstall [options] installer 22:14:11 INFO - Options: 22:14:11 INFO - -h, --help show this help message and exit 22:14:11 INFO - -d DEST, --destination=DEST 22:14:11 INFO - Directory to install application into. [default: 22:14:11 INFO - "/builds/slave/test"] 22:14:11 INFO - --app=APP Application being installed. [default: firefox] 22:14:11 INFO - mkdir: /builds/slave/test/build/application 22:14:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:14:45 INFO - Reading from file tmpfile_stdout 22:14:45 INFO - Output received: 22:14:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:14:45 INFO - Running post-action listener: _resource_record_post_action 22:14:45 INFO - ##### 22:14:45 INFO - ##### Finished install step (success) 22:14:45 INFO - ##### 22:14:45 INFO - ##### 22:14:45 INFO - ##### Running run-tests step. 22:14:45 INFO - ##### 22:14:45 INFO - Running pre-action listener: _resource_record_pre_action 22:14:45 INFO - Running main action method: run_tests 22:14:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:14:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:14:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:14:45 INFO - 2016-05-24 22:14:45.301 screenresolution[1842:9493] starting screenresolution argv=screenresolution get 22:14:45 INFO - 2016-05-24 22:14:45.304 screenresolution[1842:9493] Display 0: 1600x1200x32@60 22:14:45 INFO - 2016-05-24 22:14:45.309 screenresolution[1843:9495] starting screenresolution argv=screenresolution list 22:14:45 INFO - Available Modes on Display 0 22:14:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:14:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:14:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:14:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:14:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:14:45 INFO - Intel Iris: 22:14:45 INFO - Chipset Model: Intel Iris 22:14:45 INFO - Type: GPU 22:14:45 INFO - Bus: Built-In 22:14:45 INFO - VRAM (Dynamic, Max): 1536 MB 22:14:45 INFO - Vendor: Intel (0x8086) 22:14:45 INFO - Device ID: 0x0a2e 22:14:45 INFO - Revision ID: 0x0009 22:14:45 INFO - Displays: 22:14:45 INFO - Display: 22:14:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:14:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:14:45 INFO - Display Serial Number: 1600x1200 60 22:14:45 INFO - Main Display: Yes 22:14:45 INFO - Mirror: Off 22:14:45 INFO - Online: Yes 22:14:45 INFO - Rotation: Supported 22:14:45 INFO - Return code: 0 22:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:14:45 INFO - Python 2.7.3 22:14:45 INFO - Return code: 0 22:14:45 INFO - grabbing minidump binary from tooltool 22:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:14:45 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 0x10a20cd30>, '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 0x10a030db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a203b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:14:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:14:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:14:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:14:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:14:45 INFO - Return code: 0 22:14:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:14:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:14:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:14:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:14:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:14:46 INFO - Return code: 0 22:14:46 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/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:14:46 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/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0216 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:14:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4f3uAius1a/Render', 22:14:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:14:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:14:46 INFO - 'HOME': '/Users/cltbld', 22:14:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:14:46 INFO - 'LOGNAME': 'cltbld', 22:14:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:14:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:14:46 INFO - 'MOZ_NO_REMOTE': '1', 22:14:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:14:46 INFO - 'NO_EM_RESTART': '1', 22:14:46 INFO - 'PAGER': '/bin/cat', 22:14:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:14:46 INFO - 'PWD': '/builds/slave/test', 22:14:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:14:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:14:46 INFO - 'SHELL': '/bin/bash', 22:14:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZC1Vqg7UJV/Listeners', 22:14:46 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 22:14:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:14:46 INFO - 'USER': 'cltbld', 22:14:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:14:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:14:46 INFO - 'XPC_FLAGS': '0x0', 22:14:46 INFO - 'XPC_SERVICE_NAME': '0', 22:14:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:14:46 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/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:14:46 INFO - mozversion application_buildid: 20160524211736 22:14:46 INFO - mozversion application_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 22:14:46 INFO - mozversion application_display_name: Nightly 22:14:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:14:46 INFO - mozversion application_name: Firefox 22:14:46 INFO - mozversion application_remotingname: firefox 22:14:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:14:46 INFO - mozversion application_vendor: Mozilla 22:14:46 INFO - mozversion application_version: 49.0a1 22:14:46 INFO - mozversion platform_buildid: 20160524211736 22:14:46 INFO - mozversion platform_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 22:14:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:14:46 INFO - mozversion platform_version: 49.0a1 22:14:46 INFO - using testdate: 1464153286 22:14:46 INFO - actual date: 1464153286 22:14:46 INFO - starting webserver on 'localhost:49277' 22:14:46 INFO - SUITE-START | Running 2 tests 22:14:46 INFO - TEST-START | dromaeo_css 22:14:46 INFO - operating with platform_type : mac_ 22:14:46 INFO - Initialising browser for dromaeo_css test... 22:14:46 INFO - TEST-INFO | started process 1861 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmph_lig4/profile http://localhost:49277/getInfo.html) 22:14:49 INFO - PROCESS | 1861 | __metrics Screen width/height:1600/1200 22:14:49 INFO - PROCESS | 1861 | colorDepth:24 22:14:49 INFO - PROCESS | 1861 | Browser inner width/height: 1024/689 22:14:49 INFO - PROCESS | 1861 | __metrics 22:14:49 INFO - PROCESS | 1861 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:14:49 ERROR - PROCESS | 1861 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:14:50 INFO - TEST-INFO | 1861: exit 0 22:14:50 INFO - Browser initialized. 22:14:50 INFO - Running cycle 1/1 for dromaeo_css test... 22:14:50 INFO - TEST-INFO | started process 1864 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmph_lig4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:01 INFO - PROCESS | 1864 | RSS: Main: 239050752 22:15:01 INFO - PROCESS | 1864 | 22:15:53 INFO - PROCESS | 1864 | 1464153353431 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:18:03 INFO - PROCESS | 1864 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 22:18:04 INFO - PROCESS | 1864 | RSS: Main: 329056256 22:18:04 INFO - PROCESS | 1864 | 22:21:07 INFO - PROCESS | 1864 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 22:21:08 INFO - PROCESS | 1864 | RSS: Main: 372133888 22:21:08 INFO - PROCESS | 1864 | 22:24:10 INFO - PROCESS | 1864 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 22:24:11 INFO - PROCESS | 1864 | RSS: Main: 369917952 22:24:11 INFO - PROCESS | 1864 | 22:27:13 INFO - PROCESS | 1864 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 22:27:13 INFO - PROCESS | 1864 | RSS: Main: 372846592 22:27:13 INFO - PROCESS | 1864 | 22:30:17 INFO - PROCESS | 1864 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 22:30:17 INFO - PROCESS | 1864 | RSS: Main: 333099008 22:30:17 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 22:33:20 INFO - PROCESS | 1864 | RSS: Main: 374317056 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | __start_tp_report 22:33:20 INFO - PROCESS | 1864 | _x_x_mozilla_page_load 22:33:20 INFO - PROCESS | 1864 | _x_x_mozilla_page_load_details 22:33:20 INFO - PROCESS | 1864 | |i|pagename|runs| 22:33:20 INFO - PROCESS | 1864 | |0;dojo.html;2930.5897174395586;3473.79157250565;3493.510908472814;3505.7721248118887;3539.309834582256;5707.343655479621;5740.79907203248;5741.942580574193;5788.27646544182;5800.100984347422;5695.686737229451;5715.057015592432;5807.8257652270395;5824.213731146294;5860.062390017598;5115.360579927509;5181.248718935752;5235.816746413876;5252.501012403815;5378.596605254602;5342.519173274404;5372.247885296062;5380.435054319299;5391.865203369927;5428.782848686048;5191.688498690071;5228.8692782680355;5241.161414173714;5302.496262855023;5308.787648494055;5218.582513398919;5248.632595718302;5289.7619607117385;5293.232481290192;5297.099493086184;5191.792328306901;5202.219667049935;5204.661696989683;5242.895142097175;5328.094223981932;5654.37801841797;5706.4864162225595;5719.6854173020465;5726.484616384545;5735.827925162252;6083.365750022444;6138.204480309982;6196.008638617845;6197.194364732601;6263.561550691454;6207.379262073757;6306.747730090791;6317;6332.936670633281;6353.174087368659;5700.344460387026;5856.531477481792;5862.267216597926;5876.559258055663;5876.588638795243;6273.7176827042895;6330.493560515148;6334.714937827809;6336.20797400325;6365.649889256136;6173.228346456693;6228.159198508234;6247.656378899113;6256.124142620037;6263.843403914848;6167.352427994996;6327.987521996459;6351.9682401588625;6369.394907483782;6370.426661600478;6152.138700581922;6237.781677641261;6260.655663938436;6307.400796924285;6310.432061114522;6207.720652570645;6296.433321001132;6304.842378940563;6315.747370105144;6322.936770632235;4902.240152776325;4930.33440485537;4994.525520075587;4998.9500104999215;5052.393712754493;4478.2610869206865;4490.753008584559;4523.185826551252;4540.79566419512;4550.749708765983;5630.634008789416;5639.154126881001;5661.575381846378;5701.771929122789;5758.96338659037;6326.873462530723;6332.905006424908;6351.745930162741;6356.364363563606;6356.713947872357;5595.608307418523;5655.745491452894;5657.405972372924;5724.998125328051;5742.511886489695;4563.9087218255445;5317.73411329432;5399.2980912481125;5427.674339539641;5432.782688692408;6300.180976473029;6376.39424254695;6380.521460890452;6381.234251889803;6389.424951754364;6293.590916590407;6310.84222894413;6347.5239357047485;6349.523785715997;6356.459700925473;6247.750089996531;6298.02380631003;6320.5575609706875;6360.745570177141;6366.427021568081;6286.214223222098;6290.339514350927;6295.527835412271;6308.022256550242;6314.1160237565855;6284.340144284784;6323.525735569942;6349.49204063683;6359.459445947146;6362.809115726535;5989;6039.456448919618;6098.32918378969;6101.7016460496225;6108.736706092798;6108.706347746941;6126.090206827809;6144.003198960445;6144.525313924715;6144.709610981744;5980.43185897339;6006.009008513721;6008.218931538872;6017.909731353946;6048.213732214784;5947;6080.9662357398465;6096.496448835484;6130.172426722514;6170.53720970938;5989;6018.127371531016;6018.759249629966;6040.033594624839;6076.6961651916345 22:33:20 INFO - PROCESS | 1864 | |1;ext.html;508.5397715067865;510.94890510948886;514.9716765577894;531.8191814782973;563.1833840930649;27122.915083396667;30768.846155769246;42234;42424.57575424245;42538;44287.55712442875;44603.107937841225;44616.55383446164;44771;44828;8591.656333746643;9516.429014259133;9724.270679699015;9738.707838764813;9762.365446246007;8616.483011019327;10823.891761082366;11775.823362649568;11812.763744725144;11836.585719499819;11718.296902185853;11782.705432364217;11853.940730296337;11867.70330741734;11891.643250702493;7826.825976103573;8423.747287581382;11654.009409200227;11844.170908036442;11956.16306858516;11505.827412588818;11994;12004.159708820385;12013.279203247834;12020.278783273032;39820.80089599563;40589;40914.59085409167;41322.38016429756;41428;3142.3872344892757;3145.480995635717;3157.6526582076185;3393.0669066007044;3530.682238598513;28835.27911802201;29205.85397073001;29231.707682923112;29360;29435.558466622802;2687.046098634976;2714.267147870064;2717.673879134497;2739.328864428208;2791.2463634818487;524540;536184;536352;539648.999999996;540432;60205;60342;61748;62705;62785;35708.928732138076;36661.81669091691;36665.45001824975;36730.63269367326;65255.67372163109;17274.740878886827;17289.82710172907;17350.13249337528;17408.47774566765;17418.216180271917;11981.041516678644;12184.512619495299;12994.675133121558;13124.015698822435;13261.933690331487;20109.899450502653;20112.296069637494;20140.19439222444;20149.47384207758;20200.989950502415;8096.028476582847;8104.39217058723;8123.7156699515235;8153.714619988391;8162.469439486415;925.6852670092201;978.6427953796955;979.686500319905;1015.6343716262139;1069.8662667166604;29605.111846644635;29680.257993550335;29745.851270743507;29886;30040.399192016037;1058.333250052462;1130.4291332876844;1140.885911408869;1150.6030419505257;1205.3671822293365;12961.935190323988;15277.159756213516;15303.540893773205;15607.297671604805;15680;687493;690274.5486272536;699494.0050599428;701842;702403;60598.394016059276;61190.69404652948;61216.71482996148;61312;61357.69321153365;1244.3591550351575;1299.4996926183487;1394.6234516680236;1419.4038503828417;1456.5266288456082;24600.1389951358;28914.566281505373;29913.700862991092;30337.848310758305;31072.851777778455;42406.57593424026;51186.74406627943;52004;52156;52164.478355215964;551153;551698;566258;567072;568024;23125.19061832895;44599.77700111479;44786.3282050776;46636.300455493874;47341.76329118332;46713.76643116763;47638.76180619075;48216;49111;49275.75362123166;31440.842795785873;40043.199136016534;40305.79847100746;40706;41119;31114.53328200124;38939.415908761956;38940.41589376218;39354.40968385534;39544.60455395524;38052.61947380491;38365.232695346494;38830.805845970586;39431;39652;31484.685153148177;31757;34951.12622184364;35392.646073538934;35740.10649733777;25668.229953101436;25951.74048259493;26013.21960341193;26373;26493.94024239009 22:33:20 INFO - PROCESS | 1864 | |2;jquery.html;378.98105094745256;383.6777107229927;385.61245947822437;399.08410198594225;403.9939400908985;19246;20980;21520.677189842165;21905.233316833896;21946.122155113797;20324.491887702814;21080.683789743165;21407.036683349288;21409.67885481715;21476.355709328705;5598.944010559883;5611.382747897718;5852.736626851802;5855.590108692393;5857.326407463132;4111.732737372076;4318.784060796963;5745.166950792132;5765.567582431314;6054.182685337467;5419.783208671668;6379.202599675041;6381.457576106037;6393.136926514908;6399.39205775453;6240.157578726882;6278.843028924268;6427.453666438359;6434.0027295769;6442.25914019889;3641.417373220272;5250.527452529291;5583.804566840182;5589.273394458735;5684.7157642118145;21756.34730958074;26389.73610263892;26410.207693769025;27556.173314800388;27567.44865102687;1717.5190946534865;2004.7022647744525;2066.5040390306217;2120.257909731597;2128.9041993110345;14745.967782255246;15011.624709382244;15172.089674619448;15266.618334541614;15321.770173447407;1691.6024734187456;1701.6596680663918;1710.3500669745542;1774.7692799936056;1871.7285993530998;111348.77302453906;117755.99999999914;117919;118959;119907;9546.18857397129;10491.527881245362;10733.370324270603;11238.943805280922;11420.657380278604;11811.763764724657;13804.447822087204;14246.715065698629;14395.928020359832;14427.701506864432;7466.440016998747;8572.142785721502;8665.870011949826;8904.554772261361;8989.910100899038;7258.745943891939;8990.910090899008;8994.595243214071;9002.414843035182;9112.681056163003;5877.441643043904;6773.1872175338285;6817.795466136024;6914.747430330311;6967.686454109577;1586.6350739330019;1686.9493915182563;1745.8516026137665;1813.619139980619;1825.7900341460636;879.2965627497977;898.389095415124;981.1562056631292;1042.0933787604831;1045.3185650357227;17758.556036099202;20837.270695525585;21310.01659767634;21732.674009889863;21842.017109230124;958.4536814015944;991.1872264742985;1002.9448380339152;1012.6101450941441;1032.9173666106844;9045;10551.311790113545;11611.941940290244;11623.59317423886;11644.475998580085;599377;605510;605761;609931;617613;120554;120953;123858;126104;126864;916.3310783128354;1016.5273147986222;1025.2566889005411;1050.4800123938699;1050.81610718124;16391.04961107012;19562.924039177982;19692.50768730793;20181.789092654486;20295.79704202939;38209;51531.484685154166;52508.737456312476;52806.47193528169;52886;74649.62675186589;75224.62387688027;75388;76208;76336;7773.144954055162;7995.320397766022;8093.5953202340825;8138.470999385021;8203.42576019673;12829.422675979416;12893.613191604267;13149.21104733719;13376.197428154343;13390.531331403354;10997.285176463503;11309.095272378354;11349.659510214708;11373.54505819758;11547.538098475967;11068.896909587475;11129.83305250438;11153.49809258569;11460.426978651201;11702.70743231392;9622.278329125484;10557.52491137916;11046.33721976684;11263.605773797877;11605.419729013347;8032.317253033558;8140.267375935958;8231;8233.34133269325;8478.830423391375;11207.271527350696;11232.83150752723;11444.599439019594;11467.025302849017;11566.47950842231 22:33:20 INFO - PROCESS | 1864 | |3;mootools.html;1405.1428628536585;1551.0306058713309;1581.2489067692848;1820.2991848138463;1973.9802601973977;7944.483608565439;8756.080611535792;8949.86575201371;8985.775355616104;9078.001419843828;8605.870911936327;8745.300375969922;8870.689525866595;8871.334649901251;8880.822383552326;2088.4361222469925;2310.9537809243807;2340.9297721068397;2372.027468737818;2380.5595964746576;1786.3479829862138;2312.0867257433306;2381.84518006329;2391.928242152738;2412.6139817629182;2329.9534009319805;2350.4946436516148;2358.917437889674;2362.7637236276405;2364.869932153731;2094.811466967965;2284.4288927768057;2306.4810417655976;2314.884255787204;2326.5579539887535;2351.106579499779;2371.8695471749047;2375.4417711837705;2379.559781440439;2379.619260918262;8720.433171843873;8773;8808.779780505474;8889.822203555957;8915.866262006075;5187.092258854723;5271.42014378418;5367.543758780508;5367.624266301361;5369.167778994262;3394.066631676284;3394.405978953673;3541.2386336937425;3566.3937130687846;3568.4468907319406;1029.1201023125195;1029.5675816157232;1032.2258306270298;1032.4940779018302;1032.7986042721598;11839.644810655695;11876.762464750744;11880.287182768976;11887.346195959219;11909.583164589198;4614.423197100363;4720.88197795051;4793.760311984387;4814.109389762905;4818.373611430492;9744.12302892743;9790.951045244728;9799.51002449889;9923.40459572428;9967.800643987224;5340.305760251143;5366.302380690485;5377.5429088527135;5389.164679474688;5402.540784033323;4029.4157347184496;4387.692861499664;4548.95451045492;4873.220284754422;4899;3928;3970.68234541236;4016.036151323661;4064.3293856513937;4085.5097388313593;1292.65744577687;1303.1985329022773;1307.0719788949953;1316.4470922212502;1322.233104799214;744.6090802328821;816.986975857687;831.9417640765089;945.7257395355407;947.199616324205;5684.914726279109;5866.7653293868625;5968.1942937703725;6039.607425517327;6058.061000544834;446.89051182459855;453.605363333895;454.6249344290973;456.5252137776675;463.09233901553335;3371.258323168899;3940.4877365942243;3958.129211573449;4044.251813414469;4050.0482386639706;10291.794164116676;10356.275060745826;10726.892731072589;10849.186311026546;10849.457527123612;10654.893451065545;10666.200034997406;10785.406802625774;10797.29817561856;10797.83803242952;7929.960350198327;7998.999999999884;8009.959950200212;8021.558814265271;8163.551004694798;4575.473820510684;5760.452756988079;5879.265091863517;5890.882182356415;5948.643081415129;9662.227021838096;9773.85339219898;9898.554565044446;10108.898911010796;10147.036031576989;10083.394996300245;10191.592336306465;10192.13366863825;10198.949005254926;10211.336263142872;7318.780436586911;7403.555786652818;7429.92570074314;7487.625618718933;7492.288232617892;8111.229433203836;8126.268635822568;8185.181481852005;8198.918010819816;8207.753767386748;7184.137903451619;7291.161516425679;7411.375484229495;7448.3296503312795;7555.017847679766;7201;7249.130104387507;7288.198298187302;7435.330820226206;7438.367738742268;6895.862082758417;6944.0278361030505;6974.163100427981;6975.337342952412;6994.965025175045;4745.359376484269;4955.281484184701;4962.503749625009;4983.975080124576;5003.44962054181;4907.55831975124;4984.576311013604;4985.8005679772405;5035.420926593489;5211.218317252443 22:33:20 INFO - PROCESS | 1864 | |4;prototype.html;265.62944692154446;303.5886373963281;306.7285452374648;318.13150100226375;320;5392.460753924611;5465.453454654543;5465.551628818362;5481.260029895963;5548.58385621078;4254.6170844623975;4984.651074424792;5324.520793128618;5337.172738225581;5424.321959755031;3932.213557288547;4027.1945610877797;4091.243120022805;4139.834406623731;4252.425922500453;4095.324271495215;4140.1305725797465;4144.97927510362;4158.812853421588;4195.727277726938;4113.568075352075;4164.541900390955;4177.4778152730905;4180.6237438630515;4185.330347144458;3158.4050054722447;3890.824912878899;3936.4882565266616;4045.8381664733374;4165.5834416558255;3321.80069195849;3444.5877190351835;3606.6573675500786;3607.693346065588;3618.9276214475826;5147.716875571842;5154.536091751723;5189.948100519022;5210.76551555181;5216;4309.0483019132;4329.545397733255;4501.639868810487;4551.590356867898;4684.203685373494;5367.785288588452;5608.6634801911605;5641.464060914206;5645.943540564583;5675.971620141914;3531.7527773055895;3977.6817854571855;4000.4199391088127;4003.2393845169327;4027.456293400383;4785.856424307276;5476.863078423071;5974.8506287342725;6040.758369665208;6041.305249896274;3690.35418801714;3992.7005474589523;3994.4407782910416;4008.238434697398;4026.456428382131;4077.043019350669;4084.9591504085174;4096.931049819822;4105.34314509683;4125.546189919107;4070.8371665133063;4665.86002419928;4727.810887564528;4774.474807771142;4777.235642297216;5623.971880140573;5633.774649014075;5638.182463542763;5649.943500564942;5673.801416950387;1134.5178299222798;1257.528426839935;1411.6541447345467;1411.9011669183058;1415.60562845597;746.7834328044806;798.7883210949103;834.5159807311866;863.9913600864037;876.9166929141721;4392.538783427758;5112.721819545114;5150.253213284052;5296.642993867692;5311.442298558596;4805.567498925114;4854.470841684858;5404.459554044643;5697.208367899782;5800.245968024215;4570.725756454557;5095.363079615048;5207.895842083213;5213.739313034409;5221.329174664071;2889.8555072246722;3011.8945836895605;3017.366353065881;3036.0436462514235;3049.4663433898977;5980.654352770679;5998.940010599926;6026.246719160105;6063.756929829375;6086;5010.849674509771;5162.6902385856965;5593.860153496195;5923.111533270044;5947.431541075407;5685.715714214272;5889.823305300848;6140.385961403913;6163;6201.379862013853;4766.594839438687;4937.679050861682;5054.357333866435;5520.577242109917;5656.151577263443;4756.500567440369;5151.072806894645;5359.598030147677;5412.161115027177;5858;5328.413874473727;6015.608985415934;6144.450053227861;6151.723172457339;6156.415140561639;3493.703035242033;3909.8045097745567;3956.4462616705937;3991.5210174779213;4016.0160760613835;5752.367239603556;5773.451522105393;5793.420657934173;5798.420157984337;5807.0998995155815;5073.34046573943;5080.1363768160245;5129.486801393672;5401.648892821954;5561.888762224814;4898.4121905371585;5181.585473162213;5330.8933821324135;5636.267285252891;5658.405867383865;5505.394406615349;5516.751746171351;5527.806526771544;5528.087865502308;5535.284061940812;5075.213341932006;5319.707416092152;5576.776928922797;5714.828460165573;5723.942760572508;4603.378543896665;5074.923876141935;5414.512693857571;5472.753726082256;5626.030889188752 22:33:20 INFO - PROCESS | 1864 | |5;yui.html;620.6276234259443;639.3989649729256;726.6221564786312;734.0970606154426;741.4550305525435;12310.446029928653;12642.49430022799;15027.999999999985;15048.172350520717;15608.843911560882;13982.58052258434;14093.71812563748;14165.362558684858;14168.716625667481;14199.645008874757;729.2598013016792;755.2824816424392;816.1145157504116;900.4732231644496;909.440693973205;894.8434024045766;897.8428774964387;901.24745837226;903.3089686389917;903.746950853762;948.0708905272837;960.140674096683;961.9615215391376;963.10431298892;963.513425720014;749.187131961822;919.8114386550741;958.1711819276345;958.1855422890557;958.2238386906628;677.3531277629901;693.8508220732516;711.4166383565479;713.8001359619315;714.0859699584488;14268.714625707427;14439.278036098152;14578.489752858599;14648.853511464855;14698.55904322872;10618.469076546142;12061.216020958698;12108.576199832964;12202.75594488114;12341.259524428562;9984.70045898617;10710.51802668874;10801.945990270076;11023.61417350397;11395.202335836495;3436.570428696413;4043.534993475729;4151.647109995623;4158.6673066154635;4163.437935878648;82606;82765;84580;85044;85511.57244213739;9297.07029297065;9305.627774888928;9410.576524056434;9678.75803104928;9915.30592858493;21068;21087.472813179793;21097.68353474699;21138.365849024554;21154.15383384678;6034.758609655564;7114.359707626339;7255.52839065459;7343.265673432614;7507.849843003219;3898.1229223424502;3945.763254204757;3960.2277555876326;3964.1675248197625;4019.2966230909474;3338.8664453422075;3351.262722201112;3426.674465925783;3434.0213039284185;3438.793672379665;595.9880802383929;598.9880202395927;612.911127886454;615.5045188623201;621.9440250377488;485.2430208874168;556.5686592890543;565.1297002615926;585.3824215452738;600.1837500998638;7723.420743444264;7806.648700808477;7870.096123691446;7947.602619868983;8055.718049868344;546.4207939584054;550.7439040846026;565.3385538919474;568.9146628005832;575.2320651929721;3322.5680661513848;3634.8579182630715;3791.1848952474797;3822;3942.704297177723;87139;87476.6878496823;91157.0884291162;91892;93538.53230733803;77328.45343093107;77415.61292193503;77611.61194193993;78187.60906195546;79130.6043469779;70916.64541677362;71501;71503.28496714967;71530.64234678794;72540.63729681424;7353;7390.96304518474;7405.518641288299;7421.443391745533;7486.101667799899;45493.54506454893;45585.77207113943;45705.771471142434;45846;46399.76800115978;58637;59905.70047149737;61401;62866.685666573205;63214.41963950754;9626.614935402786;9771.413715177112;9932.453715045453;10042.297039207186;10132;10368.792624147625;10452.68641940743;10524.168267269224;10633;10850;8292.83414331722;9768.218542516724;9866.851997219905;9940.502974851373;9955.751106222402;10175.49122543855;10276.794464110826;10314.896851031694;10337.431441270803;10890.782184356427;9629.662961796303;9830.75423114428;9957.900420995697;9980.451075190935;10384.480775961021;8580.65677372923;8586;8609.612567434606;8754.69358572447;8973.50645714492;6343.777967771106;8643.956780216058;8720.345974051845;8722.651093956425;8946.284297256334 22:33:20 INFO - PROCESS | 1864 | __end_tp_report 22:33:20 INFO - PROCESS | 1864 | __start_cc_report 22:33:20 INFO - PROCESS | 1864 | _x_x_mozilla_cycle_collect,1288 22:33:20 INFO - PROCESS | 1864 | __end_cc_report 22:33:20 INFO - PROCESS | 1864 | __startTimestamp1464154400739__endTimestamp 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | ------- Summary: start ------- 22:33:20 INFO - PROCESS | 1864 | Number of tests: 6 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | [#0] dojo.html Cycles:165 Average:5792.13 Median:6018.13 stddev:648.06 (10.8%) stddev-sans-first:609.93 22:33:20 INFO - PROCESS | 1864 | Values: 2930.6 3473.8 3493.5 3505.8 3539.3 5707.3 5740.8 5741.9 5788.3 5800.1 5695.7 5715.1 5807.8 5824.2 5860.1 5115.4 5181.2 5235.8 5252.5 5378.6 5342.5 5372.2 5380.4 5391.9 5428.8 5191.7 5228.9 5241.2 5302.5 5308.8 5218.6 5248.6 5289.8 5293.2 5297.1 5191.8 5202.2 5204.7 5242.9 5328.1 5654.4 5706.5 5719.7 5726.5 5735.8 6083.4 6138.2 6196.0 6197.2 6263.6 6207.4 6306.7 6317.0 6332.9 6353.2 5700.3 5856.5 5862.3 5876.6 5876.6 6273.7 6330.5 6334.7 6336.2 6365.6 6173.2 6228.2 6247.7 6256.1 6263.8 6167.4 6328.0 6352.0 6369.4 6370.4 6152.1 6237.8 6260.7 6307.4 6310.4 6207.7 6296.4 6304.8 6315.7 6322.9 4902.2 4930.3 4994.5 4999.0 5052.4 4478.3 4490.8 4523.2 4540.8 4550.7 5630.6 5639.2 5661.6 5701.8 5759.0 6326.9 6332.9 6351.7 6356.4 6356.7 5595.6 5655.7 5657.4 5725.0 5742.5 4563.9 5317.7 5399.3 5427.7 5432.8 6300.2 6376.4 6380.5 6381.2 6389.4 6293.6 6310.8 6347.5 6349.5 6356.5 6247.8 6298.0 6320.6 6360.7 6366.4 6286.2 6290.3 6295.5 6308.0 6314.1 6284.3 6323.5 6349.5 6359.5 6362.8 5989.0 6039.5 6098.3 6101.7 6108.7 6108.7 6126.1 6144.0 6144.5 6144.7 5980.4 6006.0 6008.2 6017.9 6048.2 5947.0 6081.0 6096.5 6130.2 6170.5 5989.0 6018.1 6018.8 6040.0 6076.7 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | [#1] ext.html Cycles:180 Average:72786.99 Median:36663.63 stddev:160938.11 (439.0%) stddev-sans-first:161298.09 22:33:20 INFO - PROCESS | 1864 | Values: 508.5 510.9 515.0 531.8 563.2 27122.9 30768.8 42234.0 42424.6 42538.0 44287.6 44603.1 44616.6 44771.0 44828.0 8591.7 9516.4 9724.3 9738.7 9762.4 8616.5 10823.9 11775.8 11812.8 11836.6 11718.3 11782.7 11853.9 11867.7 11891.6 7826.8 8423.7 11654.0 11844.2 11956.2 11505.8 11994.0 12004.2 12013.3 12020.3 39820.8 40589.0 40914.6 41322.4 41428.0 3142.4 3145.5 3157.7 3393.1 3530.7 28835.3 29205.9 29231.7 29360.0 29435.6 2687.0 2714.3 2717.7 2739.3 2791.2 524540.0 536184.0 536352.0 539649.0 540432.0 60205.0 60342.0 61748.0 62705.0 62785.0 35708.9 36661.8 36665.5 36730.6 65255.7 17274.7 17289.8 17350.1 17408.5 17418.2 11981.0 12184.5 12994.7 13124.0 13261.9 20109.9 20112.3 20140.2 20149.5 20201.0 8096.0 8104.4 8123.7 8153.7 8162.5 925.7 978.6 979.7 1015.6 1069.9 29605.1 29680.3 29745.9 29886.0 30040.4 1058.3 1130.4 1140.9 1150.6 1205.4 12961.9 15277.2 15303.5 15607.3 15680.0 687493.0 690274.5 699494.0 701842.0 702403.0 60598.4 61190.7 61216.7 61312.0 61357.7 1244.4 1299.5 1394.6 1419.4 1456.5 24600.1 28914.6 29913.7 30337.8 31072.9 42406.6 51186.7 52004.0 52156.0 52164.5 551153.0 551698.0 566258.0 567072.0 568024.0 23125.2 44599.8 44786.3 46636.3 47341.8 46713.8 47638.8 48216.0 49111.0 49275.8 31440.8 40043.2 40305.8 40706.0 41119.0 31114.5 38939.4 38940.4 39354.4 39544.6 38052.6 38365.2 38830.8 39431.0 39652.0 31484.7 31757.0 34951.1 35392.6 35740.1 25668.2 25951.7 26013.2 26373.0 26493.9 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | [#2] jquery.html Cycles:180 Average:35215.82 Median:21498.52 stddev:101119.93 (470.4%) stddev-sans-first:101369.76 22:33:20 INFO - PROCESS | 1864 | Values: 379.0 383.7 385.6 399.1 404.0 19246.0 20980.0 21520.7 21905.2 21946.1 20324.5 21080.7 21407.0 21409.7 21476.4 5598.9 5611.4 5852.7 5855.6 5857.3 4111.7 4318.8 5745.2 5765.6 6054.2 5419.8 6379.2 6381.5 6393.1 6399.4 6240.2 6278.8 6427.5 6434.0 6442.3 3641.4 5250.5 5583.8 5589.3 5684.7 21756.3 26389.7 26410.2 27556.2 27567.4 1717.5 2004.7 2066.5 2120.3 2128.9 14746.0 15011.6 15172.1 15266.6 15321.8 1691.6 1701.7 1710.4 1774.8 1871.7 111348.8 117756.0 117919.0 118959.0 119907.0 9546.2 10491.5 10733.4 11238.9 11420.7 11811.8 13804.4 14246.7 14395.9 14427.7 7466.4 8572.1 8665.9 8904.6 8989.9 7258.7 8990.9 8994.6 9002.4 9112.7 5877.4 6773.2 6817.8 6914.7 6967.7 1586.6 1686.9 1745.9 1813.6 1825.8 879.3 898.4 981.2 1042.1 1045.3 17758.6 20837.3 21310.0 21732.7 21842.0 958.5 991.2 1002.9 1012.6 1032.9 9045.0 10551.3 11611.9 11623.6 11644.5 599377.0 605510.0 605761.0 609931.0 617613.0 120554.0 120953.0 123858.0 126104.0 126864.0 916.3 1016.5 1025.3 1050.5 1050.8 16391.0 19562.9 19692.5 20181.8 20295.8 38209.0 51531.5 52508.7 52806.5 52886.0 74649.6 75224.6 75388.0 76208.0 76336.0 7773.1 7995.3 8093.6 8138.5 8203.4 12829.4 12893.6 13149.2 13376.2 13390.5 10997.3 11309.1 11349.7 11373.5 11547.5 11068.9 11129.8 11153.5 11460.4 11702.7 9622.3 10557.5 11046.3 11263.6 11605.4 8032.3 8140.3 8231.0 8233.3 8478.8 11207.3 11232.8 11444.6 11467.0 11566.5 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | [#3] mootools.html Cycles:180 Average:5681.28 Median:4816.24 stddev:3233.12 (67.1%) stddev-sans-first:3226.22 22:33:20 INFO - PROCESS | 1864 | Values: 1405.1 1551.0 1581.2 1820.3 1974.0 7944.5 8756.1 8949.9 8985.8 9078.0 8605.9 8745.3 8870.7 8871.3 8880.8 2088.4 2311.0 2340.9 2372.0 2380.6 1786.3 2312.1 2381.8 2391.9 2412.6 2330.0 2350.5 2358.9 2362.8 2364.9 2094.8 2284.4 2306.5 2314.9 2326.6 2351.1 2371.9 2375.4 2379.6 2379.6 8720.4 8773.0 8808.8 8889.8 8915.9 5187.1 5271.4 5367.5 5367.6 5369.2 3394.1 3394.4 3541.2 3566.4 3568.4 1029.1 1029.6 1032.2 1032.5 1032.8 11839.6 11876.8 11880.3 11887.3 11909.6 4614.4 4720.9 4793.8 4814.1 4818.4 9744.1 9791.0 9799.5 9923.4 9967.8 5340.3 5366.3 5377.5 5389.2 5402.5 4029.4 4387.7 4549.0 4873.2 4899.0 3928.0 3970.7 4016.0 4064.3 4085.5 1292.7 1303.2 1307.1 1316.4 1322.2 744.6 817.0 831.9 945.7 947.2 5684.9 5866.8 5968.2 6039.6 6058.1 446.9 453.6 454.6 456.5 463.1 3371.3 3940.5 3958.1 4044.3 4050.0 10291.8 10356.3 10726.9 10849.2 10849.5 10654.9 10666.2 10785.4 10797.3 10797.8 7930.0 7999.0 8010.0 8021.6 8163.6 4575.5 5760.5 5879.3 5890.9 5948.6 9662.2 9773.9 9898.6 10108.9 10147.0 10083.4 10191.6 10192.1 10198.9 10211.3 7318.8 7403.6 7429.9 7487.6 7492.3 8111.2 8126.3 8185.2 8198.9 8207.8 7184.1 7291.2 7411.4 7448.3 7555.0 7201.0 7249.1 7288.2 7435.3 7438.4 6895.9 6944.0 6974.2 6975.3 6995.0 4745.4 4955.3 4962.5 4984.0 5003.4 4907.6 4984.6 4985.8 5035.4 5211.2 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | [#4] prototype.html Cycles:180 Average:4577.87 Median:5138.60 stddev:1393.74 (27.1%) stddev-sans-first:1359.55 22:33:20 INFO - PROCESS | 1864 | Values: 265.6 303.6 306.7 318.1 320.0 5392.5 5465.5 5465.6 5481.3 5548.6 4254.6 4984.7 5324.5 5337.2 5424.3 3932.2 4027.2 4091.2 4139.8 4252.4 4095.3 4140.1 4145.0 4158.8 4195.7 4113.6 4164.5 4177.5 4180.6 4185.3 3158.4 3890.8 3936.5 4045.8 4165.6 3321.8 3444.6 3606.7 3607.7 3618.9 5147.7 5154.5 5189.9 5210.8 5216.0 4309.0 4329.5 4501.6 4551.6 4684.2 5367.8 5608.7 5641.5 5645.9 5676.0 3531.8 3977.7 4000.4 4003.2 4027.5 4785.9 5476.9 5974.9 6040.8 6041.3 3690.4 3992.7 3994.4 4008.2 4026.5 4077.0 4085.0 4096.9 4105.3 4125.5 4070.8 4665.9 4727.8 4774.5 4777.2 5624.0 5633.8 5638.2 5649.9 5673.8 1134.5 1257.5 1411.7 1411.9 1415.6 746.8 798.8 834.5 864.0 876.9 4392.5 5112.7 5150.3 5296.6 5311.4 4805.6 4854.5 5404.5 5697.2 5800.2 4570.7 5095.4 5207.9 5213.7 5221.3 2889.9 3011.9 3017.4 3036.0 3049.5 5980.7 5998.9 6026.2 6063.8 6086.0 5010.8 5162.7 5593.9 5923.1 5947.4 5685.7 5889.8 6140.4 6163.0 6201.4 4766.6 4937.7 5054.4 5520.6 5656.2 4756.5 5151.1 5359.6 5412.2 5858.0 5328.4 6015.6 6144.5 6151.7 6156.4 3493.7 3909.8 3956.4 3991.5 4016.0 5752.4 5773.5 5793.4 5798.4 5807.1 5073.3 5080.1 5129.5 5401.6 5561.9 4898.4 5181.6 5330.9 5636.3 5658.4 5505.4 5516.8 5527.8 5528.1 5535.3 5075.2 5319.7 5576.8 5714.8 5723.9 4603.4 5074.9 5414.5 5472.8 5626.0 22:33:20 INFO - PROCESS | 1864 | 22:33:20 INFO - PROCESS | 1864 | [#5] yui.html Cycles:180 Average:17733.68 Median:658.38 stddev:25478.88 (3870.0%) stddev-sans-first:25517.96 22:33:20 INFO - PROCESS | 1864 | Values: 620.6 639.4 726.6 734.1 741.5 12310.4 12642.5 15028.0 15048.2 15608.8 13982.6 14093.7 14165.4 14168.7 14199.6 729.3 755.3 816.1 900.5 909.4 894.8 897.8 901.2 903.3 903.7 948.1 960.1 962.0 963.1 963.5 749.2 919.8 958.2 958.2 958.2 677.4 693.9 711.4 713.8 714.1 14268.7 14439.3 14578.5 14648.9 14698.6 10618.5 12061.2 12108.6 12202.8 12341.3 9984.7 10710.5 10801.9 11023.6 11395.2 3436.6 4043.5 4151.6 4158.7 4163.4 82606.0 82765.0 84580.0 85044.0 85511.6 9297.1 9305.6 9410.6 9678.8 9915.3 21068.0 21087.5 21097.7 21138.4 21154.2 6034.8 7114.4 7255.5 7343.3 7507.8 3898.1 3945.8 3960.2 3964.2 4019.3 3338.9 3351.3 3426.7 3434.0 3438.8 596.0 599.0 612.9 615.5 621.9 485.2 556.6 565.1 585.4 600.2 7723.4 7806.6 7870.1 7947.6 8055.7 546.4 550.7 565.3 568.9 575.2 3322.6 3634.9 3791.2 3822.0 3942.7 87139.0 87476.7 91157.1 91892.0 93538.5 77328.5 77415.6 77611.6 78187.6 79130.6 70916.6 71501.0 71503.3 71530.6 72540.6 7353.0 7391.0 7405.5 7421.4 7486.1 45493.5 45585.8 45705.8 45846.0 46399.8 58637.0 59905.7 61401.0 62866.7 63214.4 9626.6 9771.4 9932.5 10042.3 10132.0 10368.8 10452.7 10524.2 10633.0 10850.0 8292.8 9768.2 9866.9 9940.5 9955.8 10175.5 10276.8 10314.9 10337.4 10890.8 9629.7 9830.8 9957.9 9980.5 10384.5 8580.7 8586.0 8609.6 8754.7 8973.5 6343.8 8644.0 8720.3 8722.7 8946.3 22:33:20 INFO - PROCESS | 1864 | -------- Summary: end -------- 22:33:20 INFO - PROCESS | 1864 | 22:33:21 INFO - TEST-INFO | 1864: exit 0 22:33:21 INFO - TEST-OK | dromaeo_css | took 1114343ms 22:33:21 INFO - TEST-START | kraken 22:33:21 INFO - operating with platform_type : mac_ 22:33:21 INFO - Initialising browser for kraken test... 22:33:21 INFO - TEST-INFO | started process 1981 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpYwA92l/profile http://localhost:49277/getInfo.html) 22:33:23 INFO - PROCESS | 1981 | __metrics Screen width/height:1600/1200 22:33:23 INFO - PROCESS | 1981 | colorDepth:24 22:33:23 INFO - PROCESS | 1981 | Browser inner width/height: 1024/689 22:33:23 INFO - PROCESS | 1981 | __metrics 22:33:23 INFO - PROCESS | 1981 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:33:23 ERROR - PROCESS | 1981 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:33:24 INFO - TEST-INFO | 1981: exit 0 22:33:24 INFO - Browser initialized. 22:33:24 INFO - Running cycle 1/1 for kraken test... 22:33:24 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpYwA92l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:33:36 INFO - PROCESS | 1983 | RSS: Main: 239288320 22:33:36 INFO - PROCESS | 1983 | 22:33:38 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 22:33:39 INFO - PROCESS | 1983 | RSS: Main: 287383552 22:33:39 INFO - PROCESS | 1983 | 22:33:42 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 22:33:42 INFO - PROCESS | 1983 | RSS: Main: 295583744 22:33:42 INFO - PROCESS | 1983 | 22:33:46 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 22:33:46 INFO - PROCESS | 1983 | RSS: Main: 383504384 22:33:46 INFO - PROCESS | 1983 | 22:33:48 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 22:33:49 INFO - PROCESS | 1983 | RSS: Main: 284016640 22:33:49 INFO - PROCESS | 1983 | 22:33:51 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:33:52 INFO - PROCESS | 1983 | RSS: Main: 288108544 22:33:52 INFO - PROCESS | 1983 | 22:33:54 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 22:33:55 INFO - PROCESS | 1983 | RSS: Main: 325459968 22:33:55 INFO - PROCESS | 1983 | 22:33:58 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 22:33:59 INFO - PROCESS | 1983 | RSS: Main: 327655424 22:33:59 INFO - PROCESS | 1983 | 22:34:01 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 22:34:02 INFO - PROCESS | 1983 | RSS: Main: 324874240 22:34:02 INFO - PROCESS | 1983 | 22:34:04 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:34:04 INFO - PROCESS | 1983 | RSS: Main: 313122816 22:34:04 INFO - PROCESS | 1983 | 22:34:06 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:34:07 INFO - PROCESS | 1983 | RSS: Main: 320843776 22:34:07 INFO - PROCESS | 1983 | 22:34:09 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:34:10 INFO - PROCESS | 1983 | RSS: Main: 322134016 22:34:10 INFO - PROCESS | 1983 | 22:34:12 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:34:13 INFO - PROCESS | 1983 | RSS: Main: 326598656 22:34:13 INFO - PROCESS | 1983 | 22:34:16 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:34:17 INFO - PROCESS | 1983 | RSS: Main: 326062080 22:34:17 INFO - PROCESS | 1983 | 22:34:18 INFO - PROCESS | 1983 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 22:34:19 INFO - PROCESS | 1983 | RSS: Main: 373080064 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | __start_tp_report 22:34:19 INFO - PROCESS | 1983 | _x_x_mozilla_page_load 22:34:19 INFO - PROCESS | 1983 | _x_x_mozilla_page_load_details 22:34:19 INFO - PROCESS | 1983 | |i|pagename|runs| 22:34:19 INFO - PROCESS | 1983 | |0;ai-astar;114;105;114;113;112;112;113;113;112;113 22:34:19 INFO - PROCESS | 1983 | |1;audio-beat-detection;154;127;141;145;147;168;163;115;149;120 22:34:19 INFO - PROCESS | 1983 | |2;audio-dft;181;183;185;183;182;181;179;182;182;183 22:34:19 INFO - PROCESS | 1983 | |3;audio-fft;75;56;60;83;59;56;66;63;101;81 22:34:19 INFO - PROCESS | 1983 | |4;audio-oscillator;87;70;99;71;75;69;96;71;67;100 22:34:19 INFO - PROCESS | 1983 | |5;imaging-gaussian-blur;87;85;89;86;88;85;88;85;89;84 22:34:19 INFO - PROCESS | 1983 | |6;imaging-darkroom;93;95;94;93;101;93;94;94;94;94 22:34:19 INFO - PROCESS | 1983 | |7;imaging-desaturate;85;86;84;84;85;85;83;85;84;86 22:34:19 INFO - PROCESS | 1983 | |8;json-parse-financial;57;57;57;55;56;55;56;73;58;53 22:34:19 INFO - PROCESS | 1983 | |9;json-stringify-tinderbox;67;75;74;73;71;70;68;68;70;70 22:34:19 INFO - PROCESS | 1983 | |10;stanford-crypto-aes;94;84;86;89;84;86;80;102;85;88 22:34:19 INFO - PROCESS | 1983 | |11;stanford-crypto-ccm;144;145;145;137;138;147;151;148;187;154 22:34:19 INFO - PROCESS | 1983 | |12;stanford-crypto-pbkdf2;210;176;200;152;217;218;186;161;159;166 22:34:19 INFO - PROCESS | 1983 | |13;stanford-crypto-sha256-iterative;66;68;67;65;66;64;66;64;66;65 22:34:19 INFO - PROCESS | 1983 | __end_tp_report 22:34:19 INFO - PROCESS | 1983 | __start_cc_report 22:34:19 INFO - PROCESS | 1983 | _x_x_mozilla_cycle_collect,1961 22:34:19 INFO - PROCESS | 1983 | __end_cc_report 22:34:19 INFO - PROCESS | 1983 | __startTimestamp1464154459141__endTimestamp 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | ------- Summary: start ------- 22:34:19 INFO - PROCESS | 1983 | Number of tests: 14 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.60 (2.3%) stddev-sans-first:2.67 22:34:19 INFO - PROCESS | 1983 | Values: 114.0 105.0 114.0 113.0 112.0 112.0 113.0 113.0 112.0 113.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:148.00 stddev:17.56 (11.9%) stddev-sans-first:18.16 22:34:19 INFO - PROCESS | 1983 | Values: 154.0 127.0 141.0 145.0 147.0 168.0 163.0 115.0 149.0 120.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#2] audio-dft Cycles:10 Average:182.10 Median:182.50 stddev:1.60 (0.9%) stddev-sans-first:1.64 22:34:19 INFO - PROCESS | 1983 | Values: 181.0 183.0 185.0 183.0 182.0 181.0 179.0 182.0 182.0 183.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#3] audio-fft Cycles:10 Average:70.00 Median:64.50 stddev:14.73 (22.8%) stddev-sans-first:15.52 22:34:19 INFO - PROCESS | 1983 | Values: 75.0 56.0 60.0 83.0 59.0 56.0 66.0 63.0 101.0 81.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#4] audio-oscillator Cycles:10 Average:80.50 Median:73.00 stddev:13.50 (18.5%) stddev-sans-first:14.11 22:34:19 INFO - PROCESS | 1983 | Values: 87.0 70.0 99.0 71.0 75.0 69.0 96.0 71.0 67.0 100.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.84 (2.1%) stddev-sans-first:1.94 22:34:19 INFO - PROCESS | 1983 | Values: 87.0 85.0 89.0 86.0 88.0 85.0 88.0 85.0 89.0 84.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.00 stddev:2.37 (2.5%) stddev-sans-first:2.45 22:34:19 INFO - PROCESS | 1983 | Values: 93.0 95.0 94.0 93.0 101.0 93.0 94.0 94.0 94.0 94.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 22:34:19 INFO - PROCESS | 1983 | Values: 85.0 86.0 84.0 84.0 85.0 85.0 83.0 85.0 84.0 86.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.00 stddev:5.56 (9.8%) stddev-sans-first:5.89 22:34:19 INFO - PROCESS | 1983 | Values: 57.0 57.0 57.0 55.0 56.0 55.0 56.0 73.0 58.0 53.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.50 stddev:2.67 (3.8%) stddev-sans-first:2.50 22:34:19 INFO - PROCESS | 1983 | Values: 67.0 75.0 74.0 73.0 71.0 70.0 68.0 68.0 70.0 70.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:86.00 stddev:6.20 (7.2%) stddev-sans-first:6.15 22:34:19 INFO - PROCESS | 1983 | Values: 94.0 84.0 86.0 89.0 84.0 86.0 80.0 102.0 85.0 88.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:147.50 stddev:14.13 (9.6%) stddev-sans-first:14.84 22:34:19 INFO - PROCESS | 1983 | Values: 144.0 145.0 145.0 137.0 138.0 147.0 151.0 148.0 187.0 154.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:193.00 stddev:25.26 (13.1%) stddev-sans-first:25.05 22:34:19 INFO - PROCESS | 1983 | Values: 210.0 176.0 200.0 152.0 217.0 218.0 186.0 161.0 159.0 166.0 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - PROCESS | 1983 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.25 (1.9%) stddev-sans-first:1.32 22:34:19 INFO - PROCESS | 1983 | Values: 66.0 68.0 67.0 65.0 66.0 64.0 66.0 64.0 66.0 65.0 22:34:19 INFO - PROCESS | 1983 | -------- Summary: end -------- 22:34:19 INFO - PROCESS | 1983 | 22:34:19 INFO - TEST-INFO | 1983: exit 0 22:34:19 INFO - TEST-OK | kraken | took 58617ms 22:34:19 INFO - SUITE-END | took 1172s 22:34:20 INFO - Completed test suite (00:19:33) 22:34:20 INFO - javascript benchmark 22:34:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2930.5897174395586, 3473.79157250565, 3493.510908472814, 3505.7721248118887, 3539.309834582256, 5707.343655479621, 5740.79907203248, 5741.942580574193, 5788.27646544182, 5800.100984347422, 5695.686737229451, 5715.057015592432, 5807.8257652270395, 5824.213731146294, 5860.062390017598, 5115.360579927509, 5181.248718935752, 5235.816746413876, 5252.501012403815, 5378.596605254602, 5342.519173274404, 5372.247885296062, 5380.435054319299, 5391.865203369927, 5428.782848686048, 5191.688498690071, 5228.8692782680355, 5241.161414173714, 5302.496262855023, 5308.787648494055, 5218.582513398919, 5248.632595718302, 5289.7619607117385, 5293.232481290192, 5297.099493086184, 5191.792328306901, 5202.219667049935, 5204.661696989683, 5242.895142097175, 5328.094223981932, 5654.37801841797, 5706.4864162225595, 5719.6854173020465, 5726.484616384545, 5735.827925162252, 6083.365750022444, 6138.204480309982, 6196.008638617845, 6197.194364732601, 6263.561550691454, 6207.379262073757, 6306.747730090791, 6317.0, 6332.936670633281, 6353.174087368659, 5700.344460387026, 5856.531477481792, 5862.267216597926, 5876.559258055663, 5876.588638795243, 6273.7176827042895, 6330.493560515148, 6334.714937827809, 6336.20797400325, 6365.649889256136, 6173.228346456693, 6228.159198508234, 6247.656378899113, 6256.124142620037, 6263.843403914848, 6167.352427994996, 6327.987521996459, 6351.9682401588625, 6369.394907483782, 6370.426661600478, 6152.138700581922, 6237.781677641261, 6260.655663938436, 6307.400796924285, 6310.432061114522, 6207.720652570645, 6296.433321001132, 6304.842378940563, 6315.747370105144, 6322.936770632235, 4902.240152776325, 4930.33440485537, 4994.525520075587, 4998.9500104999215, 5052.393712754493, 4478.2610869206865, 4490.753008584559, 4523.185826551252, 4540.79566419512, 4550.749708765983, 5630.634008789416, 5639.154126881001, 5661.575381846378, 5701.771929122789, 5758.96338659037, 6326.873462530723, 6332.905006424908, 6351.745930162741, 6356.364363563606, 6356.713947872357, 5595.608307418523, 5655.745491452894, 5657.405972372924, 5724.998125328051, 5742.511886489695, 4563.9087218255445, 5317.73411329432, 5399.2980912481125, 5427.674339539641, 5432.782688692408, 6300.180976473029, 6376.39424254695, 6380.521460890452, 6381.234251889803, 6389.424951754364, 6293.590916590407, 6310.84222894413, 6347.5239357047485, 6349.523785715997, 6356.459700925473, 6247.750089996531, 6298.02380631003, 6320.5575609706875, 6360.745570177141, 6366.427021568081, 6286.214223222098, 6290.339514350927, 6295.527835412271, 6308.022256550242, 6314.1160237565855, 6284.340144284784, 6323.525735569942, 6349.49204063683, 6359.459445947146, 6362.809115726535, 5989.0, 6039.456448919618, 6098.32918378969, 6101.7016460496225, 6108.736706092798, 6108.706347746941, 6126.090206827809, 6144.003198960445, 6144.525313924715, 6144.709610981744, 5980.43185897339, 6006.009008513721, 6008.218931538872, 6017.909731353946, 6048.213732214784, 5947.0, 6080.9662357398465, 6096.496448835484, 6130.172426722514, 6170.53720970938, 5989.0, 6018.127371531016, 6018.759249629966, 6040.033594624839, 6076.6961651916345], "value": 5750.014006224677, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.5397715067865, 510.94890510948886, 514.9716765577894, 531.8191814782973, 563.1833840930649, 27122.915083396667, 30768.846155769246, 42234.0, 42424.57575424245, 42538.0, 44287.55712442875, 44603.107937841225, 44616.55383446164, 44771.0, 44828.0, 8591.656333746643, 9516.429014259133, 9724.270679699015, 9738.707838764813, 9762.365446246007, 8616.483011019327, 10823.891761082366, 11775.823362649568, 11812.763744725144, 11836.585719499819, 11718.296902185853, 11782.705432364217, 11853.940730296337, 11867.70330741734, 11891.643250702493, 7826.825976103573, 8423.747287581382, 11654.009409200227, 11844.170908036442, 11956.16306858516, 11505.827412588818, 11994.0, 12004.159708820385, 12013.279203247834, 12020.278783273032, 39820.80089599563, 40589.0, 40914.59085409167, 41322.38016429756, 41428.0, 3142.3872344892757, 3145.480995635717, 3157.6526582076185, 3393.0669066007044, 3530.682238598513, 28835.27911802201, 29205.85397073001, 29231.707682923112, 29360.0, 29435.558466622802, 2687.046098634976, 2714.267147870064, 2717.673879134497, 2739.328864428208, 2791.2463634818487, 524540.0, 536184.0, 536352.0, 539648.999999996, 540432.0, 60205.0, 60342.0, 61748.0, 62705.0, 62785.0, 35708.928732138076, 36661.81669091691, 36665.45001824975, 36730.63269367326, 65255.67372163109, 17274.740878886827, 17289.82710172907, 17350.13249337528, 17408.47774566765, 17418.216180271917, 11981.041516678644, 12184.512619495299, 12994.675133121558, 13124.015698822435, 13261.933690331487, 20109.899450502653, 20112.296069637494, 20140.19439222444, 20149.47384207758, 20200.989950502415, 8096.028476582847, 8104.39217058723, 8123.7156699515235, 8153.714619988391, 8162.469439486415, 925.6852670092201, 978.6427953796955, 979.686500319905, 1015.6343716262139, 1069.8662667166604, 29605.111846644635, 29680.257993550335, 29745.851270743507, 29886.0, 30040.399192016037, 1058.333250052462, 1130.4291332876844, 1140.885911408869, 1150.6030419505257, 1205.3671822293365, 12961.935190323988, 15277.159756213516, 15303.540893773205, 15607.297671604805, 15680.0, 687493.0, 690274.5486272536, 699494.0050599428, 701842.0, 702403.0, 60598.394016059276, 61190.69404652948, 61216.71482996148, 61312.0, 61357.69321153365, 1244.3591550351575, 1299.4996926183487, 1394.6234516680236, 1419.4038503828417, 1456.5266288456082, 24600.1389951358, 28914.566281505373, 29913.700862991092, 30337.848310758305, 31072.851777778455, 42406.57593424026, 51186.74406627943, 52004.0, 52156.0, 52164.478355215964, 551153.0, 551698.0, 566258.0, 567072.0, 568024.0, 23125.19061832895, 44599.77700111479, 44786.3282050776, 46636.300455493874, 47341.76329118332, 46713.76643116763, 47638.76180619075, 48216.0, 49111.0, 49275.75362123166, 31440.842795785873, 40043.199136016534, 40305.79847100746, 40706.0, 41119.0, 31114.53328200124, 38939.415908761956, 38940.41589376218, 39354.40968385534, 39544.60455395524, 38052.61947380491, 38365.232695346494, 38830.805845970586, 39431.0, 39652.0, 31484.685153148177, 31757.0, 34951.12622184364, 35392.646073538934, 35740.10649733777, 25668.229953101436, 25951.74048259493, 26013.21960341193, 26373.0, 26493.94024239009], "value": 20498.784606509, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.98105094745256, 383.6777107229927, 385.61245947822437, 399.08410198594225, 403.9939400908985, 19246.0, 20980.0, 21520.677189842165, 21905.233316833896, 21946.122155113797, 20324.491887702814, 21080.683789743165, 21407.036683349288, 21409.67885481715, 21476.355709328705, 5598.944010559883, 5611.382747897718, 5852.736626851802, 5855.590108692393, 5857.326407463132, 4111.732737372076, 4318.784060796963, 5745.166950792132, 5765.567582431314, 6054.182685337467, 5419.783208671668, 6379.202599675041, 6381.457576106037, 6393.136926514908, 6399.39205775453, 6240.157578726882, 6278.843028924268, 6427.453666438359, 6434.0027295769, 6442.25914019889, 3641.417373220272, 5250.527452529291, 5583.804566840182, 5589.273394458735, 5684.7157642118145, 21756.34730958074, 26389.73610263892, 26410.207693769025, 27556.173314800388, 27567.44865102687, 1717.5190946534865, 2004.7022647744525, 2066.5040390306217, 2120.257909731597, 2128.9041993110345, 14745.967782255246, 15011.624709382244, 15172.089674619448, 15266.618334541614, 15321.770173447407, 1691.6024734187456, 1701.6596680663918, 1710.3500669745542, 1774.7692799936056, 1871.7285993530998, 111348.77302453906, 117755.99999999914, 117919.0, 118959.0, 119907.0, 9546.18857397129, 10491.527881245362, 10733.370324270603, 11238.943805280922, 11420.657380278604, 11811.763764724657, 13804.447822087204, 14246.715065698629, 14395.928020359832, 14427.701506864432, 7466.440016998747, 8572.142785721502, 8665.870011949826, 8904.554772261361, 8989.910100899038, 7258.745943891939, 8990.910090899008, 8994.595243214071, 9002.414843035182, 9112.681056163003, 5877.441643043904, 6773.1872175338285, 6817.795466136024, 6914.747430330311, 6967.686454109577, 1586.6350739330019, 1686.9493915182563, 1745.8516026137665, 1813.619139980619, 1825.7900341460636, 879.2965627497977, 898.389095415124, 981.1562056631292, 1042.0933787604831, 1045.3185650357227, 17758.556036099202, 20837.270695525585, 21310.01659767634, 21732.674009889863, 21842.017109230124, 958.4536814015944, 991.1872264742985, 1002.9448380339152, 1012.6101450941441, 1032.9173666106844, 9045.0, 10551.311790113545, 11611.941940290244, 11623.59317423886, 11644.475998580085, 599377.0, 605510.0, 605761.0, 609931.0, 617613.0, 120554.0, 120953.0, 123858.0, 126104.0, 126864.0, 916.3310783128354, 1016.5273147986222, 1025.2566889005411, 1050.4800123938699, 1050.81610718124, 16391.04961107012, 19562.924039177982, 19692.50768730793, 20181.789092654486, 20295.79704202939, 38209.0, 51531.484685154166, 52508.737456312476, 52806.47193528169, 52886.0, 74649.62675186589, 75224.62387688027, 75388.0, 76208.0, 76336.0, 7773.144954055162, 7995.320397766022, 8093.5953202340825, 8138.470999385021, 8203.42576019673, 12829.422675979416, 12893.613191604267, 13149.21104733719, 13376.197428154343, 13390.531331403354, 10997.285176463503, 11309.095272378354, 11349.659510214708, 11373.54505819758, 11547.538098475967, 11068.896909587475, 11129.83305250438, 11153.49809258569, 11460.426978651201, 11702.70743231392, 9622.278329125484, 10557.52491137916, 11046.33721976684, 11263.605773797877, 11605.419729013347, 8032.317253033558, 8140.267375935958, 8231.0, 8233.34133269325, 8478.830423391375, 11207.271527350696, 11232.83150752723, 11444.599439019594, 11467.025302849017, 11566.47950842231], "value": 9714.181388422377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.1428628536585, 1551.0306058713309, 1581.2489067692848, 1820.2991848138463, 1973.9802601973977, 7944.483608565439, 8756.080611535792, 8949.86575201371, 8985.775355616104, 9078.001419843828, 8605.870911936327, 8745.300375969922, 8870.689525866595, 8871.334649901251, 8880.822383552326, 2088.4361222469925, 2310.9537809243807, 2340.9297721068397, 2372.027468737818, 2380.5595964746576, 1786.3479829862138, 2312.0867257433306, 2381.84518006329, 2391.928242152738, 2412.6139817629182, 2329.9534009319805, 2350.4946436516148, 2358.917437889674, 2362.7637236276405, 2364.869932153731, 2094.811466967965, 2284.4288927768057, 2306.4810417655976, 2314.884255787204, 2326.5579539887535, 2351.106579499779, 2371.8695471749047, 2375.4417711837705, 2379.559781440439, 2379.619260918262, 8720.433171843873, 8773.0, 8808.779780505474, 8889.822203555957, 8915.866262006075, 5187.092258854723, 5271.42014378418, 5367.543758780508, 5367.624266301361, 5369.167778994262, 3394.066631676284, 3394.405978953673, 3541.2386336937425, 3566.3937130687846, 3568.4468907319406, 1029.1201023125195, 1029.5675816157232, 1032.2258306270298, 1032.4940779018302, 1032.7986042721598, 11839.644810655695, 11876.762464750744, 11880.287182768976, 11887.346195959219, 11909.583164589198, 4614.423197100363, 4720.88197795051, 4793.760311984387, 4814.109389762905, 4818.373611430492, 9744.12302892743, 9790.951045244728, 9799.51002449889, 9923.40459572428, 9967.800643987224, 5340.305760251143, 5366.302380690485, 5377.5429088527135, 5389.164679474688, 5402.540784033323, 4029.4157347184496, 4387.692861499664, 4548.95451045492, 4873.220284754422, 4899.0, 3928.0, 3970.68234541236, 4016.036151323661, 4064.3293856513937, 4085.5097388313593, 1292.65744577687, 1303.1985329022773, 1307.0719788949953, 1316.4470922212502, 1322.233104799214, 744.6090802328821, 816.986975857687, 831.9417640765089, 945.7257395355407, 947.199616324205, 5684.914726279109, 5866.7653293868625, 5968.1942937703725, 6039.607425517327, 6058.061000544834, 446.89051182459855, 453.605363333895, 454.6249344290973, 456.5252137776675, 463.09233901553335, 3371.258323168899, 3940.4877365942243, 3958.129211573449, 4044.251813414469, 4050.0482386639706, 10291.794164116676, 10356.275060745826, 10726.892731072589, 10849.186311026546, 10849.457527123612, 10654.893451065545, 10666.200034997406, 10785.406802625774, 10797.29817561856, 10797.83803242952, 7929.960350198327, 7998.999999999884, 8009.959950200212, 8021.558814265271, 8163.551004694798, 4575.473820510684, 5760.452756988079, 5879.265091863517, 5890.882182356415, 5948.643081415129, 9662.227021838096, 9773.85339219898, 9898.554565044446, 10108.898911010796, 10147.036031576989, 10083.394996300245, 10191.592336306465, 10192.13366863825, 10198.949005254926, 10211.336263142872, 7318.780436586911, 7403.555786652818, 7429.92570074314, 7487.625618718933, 7492.288232617892, 8111.229433203836, 8126.268635822568, 8185.181481852005, 8198.918010819816, 8207.753767386748, 7184.137903451619, 7291.161516425679, 7411.375484229495, 7448.3296503312795, 7555.017847679766, 7201.0, 7249.130104387507, 7288.198298187302, 7435.330820226206, 7438.367738742268, 6895.862082758417, 6944.0278361030505, 6974.163100427981, 6975.337342952412, 6994.965025175045, 4745.359376484269, 4955.281484184701, 4962.503749625009, 4983.975080124576, 5003.44962054181, 4907.55831975124, 4984.576311013604, 4985.8005679772405, 5035.420926593489, 5211.218317252443], "value": 4473.894564848777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.62944692154446, 303.5886373963281, 306.7285452374648, 318.13150100226375, 320.0, 5392.460753924611, 5465.453454654543, 5465.551628818362, 5481.260029895963, 5548.58385621078, 4254.6170844623975, 4984.651074424792, 5324.520793128618, 5337.172738225581, 5424.321959755031, 3932.213557288547, 4027.1945610877797, 4091.243120022805, 4139.834406623731, 4252.425922500453, 4095.324271495215, 4140.1305725797465, 4144.97927510362, 4158.812853421588, 4195.727277726938, 4113.568075352075, 4164.541900390955, 4177.4778152730905, 4180.6237438630515, 4185.330347144458, 3158.4050054722447, 3890.824912878899, 3936.4882565266616, 4045.8381664733374, 4165.5834416558255, 3321.80069195849, 3444.5877190351835, 3606.6573675500786, 3607.693346065588, 3618.9276214475826, 5147.716875571842, 5154.536091751723, 5189.948100519022, 5210.76551555181, 5216.0, 4309.0483019132, 4329.545397733255, 4501.639868810487, 4551.590356867898, 4684.203685373494, 5367.785288588452, 5608.6634801911605, 5641.464060914206, 5645.943540564583, 5675.971620141914, 3531.7527773055895, 3977.6817854571855, 4000.4199391088127, 4003.2393845169327, 4027.456293400383, 4785.856424307276, 5476.863078423071, 5974.8506287342725, 6040.758369665208, 6041.305249896274, 3690.35418801714, 3992.7005474589523, 3994.4407782910416, 4008.238434697398, 4026.456428382131, 4077.043019350669, 4084.9591504085174, 4096.931049819822, 4105.34314509683, 4125.546189919107, 4070.8371665133063, 4665.86002419928, 4727.810887564528, 4774.474807771142, 4777.235642297216, 5623.971880140573, 5633.774649014075, 5638.182463542763, 5649.943500564942, 5673.801416950387, 1134.5178299222798, 1257.528426839935, 1411.6541447345467, 1411.9011669183058, 1415.60562845597, 746.7834328044806, 798.7883210949103, 834.5159807311866, 863.9913600864037, 876.9166929141721, 4392.538783427758, 5112.721819545114, 5150.253213284052, 5296.642993867692, 5311.442298558596, 4805.567498925114, 4854.470841684858, 5404.459554044643, 5697.208367899782, 5800.245968024215, 4570.725756454557, 5095.363079615048, 5207.895842083213, 5213.739313034409, 5221.329174664071, 2889.8555072246722, 3011.8945836895605, 3017.366353065881, 3036.0436462514235, 3049.4663433898977, 5980.654352770679, 5998.940010599926, 6026.246719160105, 6063.756929829375, 6086.0, 5010.849674509771, 5162.6902385856965, 5593.860153496195, 5923.111533270044, 5947.431541075407, 5685.715714214272, 5889.823305300848, 6140.385961403913, 6163.0, 6201.379862013853, 4766.594839438687, 4937.679050861682, 5054.357333866435, 5520.577242109917, 5656.151577263443, 4756.500567440369, 5151.072806894645, 5359.598030147677, 5412.161115027177, 5858.0, 5328.413874473727, 6015.608985415934, 6144.450053227861, 6151.723172457339, 6156.415140561639, 3493.703035242033, 3909.8045097745567, 3956.4462616705937, 3991.5210174779213, 4016.0160760613835, 5752.367239603556, 5773.451522105393, 5793.420657934173, 5798.420157984337, 5807.0998995155815, 5073.34046573943, 5080.1363768160245, 5129.486801393672, 5401.648892821954, 5561.888762224814, 4898.4121905371585, 5181.585473162213, 5330.8933821324135, 5636.267285252891, 5658.405867383865, 5505.394406615349, 5516.751746171351, 5527.806526771544, 5528.087865502308, 5535.284061940812, 5075.213341932006, 5319.707416092152, 5576.776928922797, 5714.828460165573, 5723.942760572508, 4603.378543896665, 5074.923876141935, 5414.512693857571, 5472.753726082256, 5626.030889188752], "value": 4123.726245887429, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.6276234259443, 639.3989649729256, 726.6221564786312, 734.0970606154426, 741.4550305525435, 12310.446029928653, 12642.49430022799, 15027.999999999985, 15048.172350520717, 15608.843911560882, 13982.58052258434, 14093.71812563748, 14165.362558684858, 14168.716625667481, 14199.645008874757, 729.2598013016792, 755.2824816424392, 816.1145157504116, 900.4732231644496, 909.440693973205, 894.8434024045766, 897.8428774964387, 901.24745837226, 903.3089686389917, 903.746950853762, 948.0708905272837, 960.140674096683, 961.9615215391376, 963.10431298892, 963.513425720014, 749.187131961822, 919.8114386550741, 958.1711819276345, 958.1855422890557, 958.2238386906628, 677.3531277629901, 693.8508220732516, 711.4166383565479, 713.8001359619315, 714.0859699584488, 14268.714625707427, 14439.278036098152, 14578.489752858599, 14648.853511464855, 14698.55904322872, 10618.469076546142, 12061.216020958698, 12108.576199832964, 12202.75594488114, 12341.259524428562, 9984.70045898617, 10710.51802668874, 10801.945990270076, 11023.61417350397, 11395.202335836495, 3436.570428696413, 4043.534993475729, 4151.647109995623, 4158.6673066154635, 4163.437935878648, 82606.0, 82765.0, 84580.0, 85044.0, 85511.57244213739, 9297.07029297065, 9305.627774888928, 9410.576524056434, 9678.75803104928, 9915.30592858493, 21068.0, 21087.472813179793, 21097.68353474699, 21138.365849024554, 21154.15383384678, 6034.758609655564, 7114.359707626339, 7255.52839065459, 7343.265673432614, 7507.849843003219, 3898.1229223424502, 3945.763254204757, 3960.2277555876326, 3964.1675248197625, 4019.2966230909474, 3338.8664453422075, 3351.262722201112, 3426.674465925783, 3434.0213039284185, 3438.793672379665, 595.9880802383929, 598.9880202395927, 612.911127886454, 615.5045188623201, 621.9440250377488, 485.2430208874168, 556.5686592890543, 565.1297002615926, 585.3824215452738, 600.1837500998638, 7723.420743444264, 7806.648700808477, 7870.096123691446, 7947.602619868983, 8055.718049868344, 546.4207939584054, 550.7439040846026, 565.3385538919474, 568.9146628005832, 575.2320651929721, 3322.5680661513848, 3634.8579182630715, 3791.1848952474797, 3822.0, 3942.704297177723, 87139.0, 87476.6878496823, 91157.0884291162, 91892.0, 93538.53230733803, 77328.45343093107, 77415.61292193503, 77611.61194193993, 78187.60906195546, 79130.6043469779, 70916.64541677362, 71501.0, 71503.28496714967, 71530.64234678794, 72540.63729681424, 7353.0, 7390.96304518474, 7405.518641288299, 7421.443391745533, 7486.101667799899, 45493.54506454893, 45585.77207113943, 45705.771471142434, 45846.0, 46399.76800115978, 58637.0, 59905.70047149737, 61401.0, 62866.685666573205, 63214.41963950754, 9626.614935402786, 9771.413715177112, 9932.453715045453, 10042.297039207186, 10132.0, 10368.792624147625, 10452.68641940743, 10524.168267269224, 10633.0, 10850.0, 8292.83414331722, 9768.218542516724, 9866.851997219905, 9940.502974851373, 9955.751106222402, 10175.49122543855, 10276.794464110826, 10314.896851031694, 10337.431441270803, 10890.782184356427, 9629.662961796303, 9830.75423114428, 9957.900420995697, 9980.451075190935, 10384.480775961021, 8580.65677372923, 8586.0, 8609.612567434606, 8754.69358572447, 8973.50645714492, 6343.777967771106, 8643.956780216058, 8720.345974051845, 8722.651093956425, 8946.284297256334], "value": 6604.5066873328715, "unit": "score"}], "name": "dromaeo_css", "value": 7201.856873770359, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 105.0, 114.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 127.0, 141.0, 145.0, 147.0, 168.0, 163.0, 115.0, 149.0, 120.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 185.0, 183.0, 182.0, 181.0, 179.0, 182.0, 182.0, 183.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 56.0, 60.0, 83.0, 59.0, 56.0, 66.0, 63.0, 101.0, 81.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 99.0, 71.0, 75.0, 69.0, 96.0, 71.0, 67.0, 100.0], "value": 80.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 86.0, 88.0, 85.0, 88.0, 85.0, 89.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 93.0, 101.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 84.0, 85.0, 85.0, 83.0, 85.0, 84.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 55.0, 56.0, 55.0, 56.0, 73.0, 58.0, 53.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 74.0, 73.0, 71.0, 70.0, 68.0, 68.0, 70.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 84.0, 86.0, 89.0, 84.0, 86.0, 80.0, 102.0, 85.0, 88.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 145.0, 145.0, 137.0, 138.0, 147.0, 151.0, 148.0, 187.0, 154.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 176.0, 200.0, 152.0, 217.0, 218.0, 186.0, 161.0, 159.0, 166.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 65.0, 66.0, 64.0, 66.0, 64.0, 66.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1469.3000000000002, "alertThreshold": 2.0}]} 22:34:20 INFO - Return code: 0 22:34:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:34:20 ERROR - # TBPL SUCCESS # 22:34:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:34:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:34:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:34:20 INFO - 2016-05-24 22:34:20.467 screenresolution[1993:11847] starting screenresolution argv=screenresolution get 22:34:20 INFO - 2016-05-24 22:34:20.469 screenresolution[1993:11847] Display 0: 1600x1200x32@60 22:34:20 INFO - 2016-05-24 22:34:20.475 screenresolution[1994:11850] starting screenresolution argv=screenresolution list 22:34:20 INFO - Available Modes on Display 0 22:34:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:34:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:34:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:34:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:34:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:34:20 INFO - Intel Iris: 22:34:20 INFO - Chipset Model: Intel Iris 22:34:20 INFO - Type: GPU 22:34:20 INFO - Bus: Built-In 22:34:20 INFO - VRAM (Dynamic, Max): 1536 MB 22:34:20 INFO - Vendor: Intel (0x8086) 22:34:20 INFO - Device ID: 0x0a2e 22:34:20 INFO - Revision ID: 0x0009 22:34:20 INFO - Displays: 22:34:20 INFO - Display: 22:34:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:34:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:34:20 INFO - Display Serial Number: 1600x1200 60 22:34:20 INFO - Main Display: Yes 22:34:20 INFO - Mirror: Off 22:34:20 INFO - Online: Yes 22:34:20 INFO - Rotation: Supported 22:34:20 INFO - Return code: 0 22:34:20 INFO - Running post-action listener: _resource_record_post_action 22:34:20 INFO - ##### 22:34:20 INFO - ##### Finished run-tests step (success) 22:34:20 INFO - ##### 22:34:20 INFO - Running post-run listener: _resource_record_post_run 22:34:20 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 31514112; Write bytes: 700529664; Read time: 197; Write time: 1939 22:34:20 INFO - TinderboxPrint: CPU usage
53.8% 22:34:20 INFO - TinderboxPrint: I/O read bytes / time
31,514,112 / 197 22:34:20 INFO - TinderboxPrint: I/O write bytes / time
700,529,664 / 1,939 22:34:20 INFO - TinderboxPrint: CPU idle
2,233.7 (46.2%) 22:34:20 INFO - TinderboxPrint: CPU system
973.4 (20.1%) 22:34:20 INFO - TinderboxPrint: CPU user
1,630.3 (33.7%) 22:34:20 INFO - TinderboxPrint: Swap in / out
423,755,776 / 0 22:34:20 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 249856; Write bytes: 400380416; Read time: 5; Write time: 825 22:34:20 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 29720064; Write bytes: 299301376; Read time: 180; Write time: 1112 22:34:21 INFO - Running post-run listener: _upload_blobber_files 22:34:21 INFO - Blob upload gear active. 22:34:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:34:21 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 22:34:21 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'] 22:34:21 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 22:34:21 INFO - (blobuploader) - INFO - Open directory for files ... 22:34:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:34:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:34:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:22 INFO - (blobuploader) - INFO - Done attempting. 22:34:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:34:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:34:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:23 INFO - (blobuploader) - INFO - Done attempting. 22:34:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:34:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:34:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:34:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:34:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:34:25 INFO - (blobuploader) - INFO - Done attempting. 22:34:25 INFO - (blobuploader) - INFO - Iteration through files over. 22:34:25 INFO - Return code: 0 22:34:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:34:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:34:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26a7a0bcff1c06b397f625e29a6ac438abb9ec7279db61f41f4642f88ef99eb3218294eb4101d5ae2e593e03e776562489c20b06c19d2c075944a275eceabc7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2591bef1d311533770cbea63345322c7897cbc37ee61f09f6537cc9b4ffa4613bed4606eee9a021b06975a485f3364736e9df9885383221c837dd468e9f4e0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c7a7fce7d1bfa6db8e612bc3944c77152bf6d47380d03375c1b255fc0f384451901834e5647c7ab95279f2899e5ec5963405c0d4ff540a306ddf2f17e37dfd4"} 22:34:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:34:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:34:25 INFO - Contents: 22:34:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26a7a0bcff1c06b397f625e29a6ac438abb9ec7279db61f41f4642f88ef99eb3218294eb4101d5ae2e593e03e776562489c20b06c19d2c075944a275eceabc7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2591bef1d311533770cbea63345322c7897cbc37ee61f09f6537cc9b4ffa4613bed4606eee9a021b06975a485f3364736e9df9885383221c837dd468e9f4e0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c7a7fce7d1bfa6db8e612bc3944c77152bf6d47380d03375c1b255fc0f384451901834e5647c7ab95279f2899e5ec5963405c0d4ff540a306ddf2f17e37dfd4"} 22:34:25 INFO - Running post-run listener: copy_logs_to_upload_dir 22:34:25 INFO - Copying logs to upload dir... 22:34:25 INFO - mkdir: /builds/slave/test/build/upload/logs 22:34:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.480182 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-24 22:34:25.992936) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 22:34:25.997021) ========= 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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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/26a7a0bcff1c06b397f625e29a6ac438abb9ec7279db61f41f4642f88ef99eb3218294eb4101d5ae2e593e03e776562489c20b06c19d2c075944a275eceabc7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2591bef1d311533770cbea63345322c7897cbc37ee61f09f6537cc9b4ffa4613bed4606eee9a021b06975a485f3364736e9df9885383221c837dd468e9f4e0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c7a7fce7d1bfa6db8e612bc3944c77152bf6d47380d03375c1b255fc0f384451901834e5647c7ab95279f2899e5ec5963405c0d4ff540a306ddf2f17e37dfd4"} build_url:https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://queue.taskcluster.net/v1/task/PafH1uPBSLeCJFVM6dwtFg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26a7a0bcff1c06b397f625e29a6ac438abb9ec7279db61f41f4642f88ef99eb3218294eb4101d5ae2e593e03e776562489c20b06c19d2c075944a275eceabc7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2591bef1d311533770cbea63345322c7897cbc37ee61f09f6537cc9b4ffa4613bed4606eee9a021b06975a485f3364736e9df9885383221c837dd468e9f4e0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5c7a7fce7d1bfa6db8e612bc3944c77152bf6d47380d03375c1b255fc0f384451901834e5647c7ab95279f2899e5ec5963405c0d4ff540a306ddf2f17e37dfd4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 22:34:26.027637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:34:26.028067) ========= 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.4f3uAius1a/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.ZC1Vqg7UJV/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005080 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 22:34:26.076486) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 22:34:26.076830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 22:34:26.077175) ========= ========= Total master_lag: 0.20 =========