builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0004 starttime: 1463426207.49 results: success (0) buildid: 20160516100443 builduid: 3d831e24ae854dbdb8ef0aac6c54c99a revision: 133ef863766e96786f6147f008cb4b95867857f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.493852) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.494321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.494602) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.519349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.519731) ========= 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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-16 12:16:47-- 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% 58.2M=0s 2016-05-16 12:16:47 (58.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086786 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.864234) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.867554) ========= 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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.019482 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:47.961821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-16 12:16:47.962168) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 133ef863766e96786f6147f008cb4b95867857f0 --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 133ef863766e96786f6147f008cb4b95867857f0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-16 12:16:48,728 truncating revision to first 12 chars 2016-05-16 12:16:48,728 Setting DEBUG logging. 2016-05-16 12:16:48,728 attempt 1/10 2016-05-16 12:16:48,728 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/133ef863766e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 12:16:49,770 unpacking tar archive at: fx-team-133ef863766e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.290430 ========= master_lag: 1.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-16 12:16:50.413683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:50.413982) ========= script_repo_revision: 133ef863766e96786f6147f008cb4b95867857f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:50.414376) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:50.414635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 12:16:50.440265) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-16 12:16:50.440579) ========= /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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:16:50 INFO - MultiFileLogger online at 20160516 12:16:50 in /builds/slave/test 12:16:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:16:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:16:50 INFO - {'append_to_log': False, 12:16:50 INFO - 'base_work_dir': '/builds/slave/test', 12:16:50 INFO - 'blob_upload_branch': 'Fx-Team', 12:16:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:16:50 INFO - 'branch': 'Fx-Team', 12:16:50 INFO - 'buildbot_json_path': 'buildprops.json', 12:16:50 INFO - 'config_files': ('talos/mac_config.py',), 12:16:50 INFO - 'default_actions': ('clobber', 12:16:50 INFO - 'read-buildbot-config', 12:16:50 INFO - 'download-and-extract', 12:16:50 INFO - 'populate-webroot', 12:16:50 INFO - 'create-virtualenv', 12:16:50 INFO - 'install', 12:16:50 INFO - 'run-tests'), 12:16:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:16:50 INFO - 'download_minidump_stackwalk': True, 12:16:50 INFO - 'download_symbols': 'ondemand', 12:16:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:16:50 INFO - 'tooltool.py': '/tools/tooltool.py', 12:16:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:16:50 INFO - '/tools/misc-python/virtualenv.py')}, 12:16:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:16:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:16:50 INFO - 'installer_path': 'installer.exe', 12:16:50 INFO - 'log_level': 'info', 12:16:50 INFO - 'log_name': 'talos', 12:16:50 INFO - 'log_to_console': True, 12:16:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:16:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:16:50 INFO - 'opt_config_files': (), 12:16:50 INFO - 'pip_index': False, 12:16:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:16:50 INFO - 'cmd': ('bash', 12:16:50 INFO - '-c', 12:16:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:16:50 INFO - 'enabled': True, 12:16:50 INFO - 'halt_on_failure': False, 12:16:50 INFO - 'name': 'check_screen_resolution'},), 12:16:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:16:50 INFO - 'cmd': ('bash', 12:16:50 INFO - '-c', 12:16:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:16:50 INFO - 'enabled': True, 12:16:50 INFO - 'halt_on_failure': False, 12:16:50 INFO - 'name': 'check_screen_resolution'},), 12:16:50 INFO - 'run_cmd_checks_enabled': True, 12:16:50 INFO - 'sps_profile': False, 12:16:50 INFO - 'sps_profile_interval': 0, 12:16:50 INFO - 'suite': 'dromaeojs', 12:16:50 INFO - 'system_bits': '32', 12:16:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:16:50 INFO - 'title': 't-yosemite-r7-0004', 12:16:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:16:50 INFO - 'use_talos_json': True, 12:16:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:16:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:16:50 INFO - 'work_dir': 'build'} 12:16:50 INFO - ##### 12:16:50 INFO - ##### Running clobber step. 12:16:50 INFO - ##### 12:16:50 INFO - Running pre-action listener: _resource_record_pre_action 12:16:50 INFO - Running main action method: clobber 12:16:50 INFO - rmtree: /builds/slave/test/build 12:16:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:16:52 INFO - Running post-action listener: _resource_record_post_action 12:16:52 INFO - ##### 12:16:52 INFO - ##### Finished clobber step (success) 12:16:52 INFO - ##### 12:16:52 INFO - ##### 12:16:52 INFO - ##### Running read-buildbot-config step. 12:16:52 INFO - ##### 12:16:52 INFO - Running pre-action listener: _resource_record_pre_action 12:16:52 INFO - Running main action method: read_buildbot_config 12:16:52 INFO - Using buildbot properties: 12:16:52 INFO - { 12:16:52 INFO - "project": "", 12:16:52 INFO - "product": "firefox", 12:16:52 INFO - "script_repo_revision": "production", 12:16:52 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:16:52 INFO - "repository": "", 12:16:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:16:52 INFO - "buildid": "20160516100443", 12:16:52 INFO - "pgo_build": "False", 12:16:52 INFO - "basedir": "/builds/slave/test", 12:16:52 INFO - "buildnumber": 820, 12:16:52 INFO - "slavename": "t-yosemite-r7-0004", 12:16:52 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:16:52 INFO - "platform": "yosemite_r7", 12:16:52 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:16:52 INFO - "branch": "fx-team", 12:16:52 INFO - "repo_path": "integration/fx-team", 12:16:52 INFO - "revision": "133ef863766e96786f6147f008cb4b95867857f0", 12:16:52 INFO - "stage_platform": "macosx64", 12:16:52 INFO - "builduid": "3d831e24ae854dbdb8ef0aac6c54c99a", 12:16:52 INFO - "slavebuilddir": "test" 12:16:52 INFO - } 12:16:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:16:52 INFO - Running post-action listener: _resource_record_post_action 12:16:52 INFO - ##### 12:16:52 INFO - ##### Finished read-buildbot-config step (success) 12:16:52 INFO - ##### 12:16:52 INFO - ##### 12:16:52 INFO - ##### Running download-and-extract step. 12:16:52 INFO - ##### 12:16:52 INFO - Running pre-action listener: _resource_record_pre_action 12:16:52 INFO - Running main action method: download_and_extract 12:16:52 INFO - mkdir: /builds/slave/test/build/tests 12:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:16:52 INFO - https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:16:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:16:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:16:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:16:53 INFO - Downloaded 1199 bytes. 12:16:53 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:16:53 INFO - Using the following test package requirements: 12:16:53 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:16:53 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:16:53 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:16:53 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'jsshell-mac.zip'], 12:16:53 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:16:53 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:16:53 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:16:53 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:16:53 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:16:53 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:16:53 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:16:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:16: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')]} 12:16:53 INFO - https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:16:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:16:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:16:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:16:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:16:55 INFO - Downloaded 18267715 bytes. 12:16:55 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'] 12:16:55 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 12:16:59 INFO - Return code: 0 12:16:59 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 12:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:16:59 INFO - https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:16:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:16:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:16:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:17:00 INFO - Downloaded 18267715 bytes. 12:17:00 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'] 12:17:00 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 12:17:03 INFO - Return code: 0 12:17: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')]} 12:17:03 INFO - https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:17:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:17:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:17:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:17:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:17:04 INFO - Downloaded 10840194 bytes. 12:17:04 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'] 12:17:04 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 12:17:05 INFO - Return code: 0 12:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:05 INFO - https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:17:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:17:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:17:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:17:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b-WmbG2fSM-OjwsePigp2w/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 12:17:08 INFO - Downloaded 110053783 bytes. 12:17:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:17:08 INFO - mkdir: /builds/slave/test/properties 12:17:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:17:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:17:08 INFO - Contents: 12:17:08 INFO - build_url:https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:17:09 INFO - Running post-action listener: _resource_record_post_action 12:17:09 INFO - Running post-action listener: set_extra_try_arguments 12:17:09 INFO - ##### 12:17:09 INFO - ##### Finished download-and-extract step (success) 12:17:09 INFO - ##### 12:17:09 INFO - ##### 12:17:09 INFO - ##### Running populate-webroot step. 12:17:09 INFO - ##### 12:17:09 INFO - Running pre-action listener: _resource_record_pre_action 12:17:09 INFO - Running main action method: populate_webroot 12:17:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:17:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:17:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:17:09 INFO - '--tppagecycles', 12:17:09 INFO - '7'], 12:17:09 INFO - 'tests': ['tsvgm']}}, 12:17:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:17:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:17:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:17:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:17:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['damp', 'tps']}, 12:17:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'tests': ['damp', 'tps']}, 12:17:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['dromaeo_dom']}, 12:17:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:17:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['a11yr', 12:17:09 INFO - 'ts_paint', 12:17:09 INFO - 'tpaint', 12:17:09 INFO - 'sessionrestore', 12:17:09 INFO - 'sessionrestore_no_auto_restore', 12:17:09 INFO - 'tabpaint']}, 12:17:09 INFO - 'other-e10s': {'tests': ['a11yr', 12:17:09 INFO - 'ts_paint', 12:17:09 INFO - 'tpaint', 12:17:09 INFO - 'sessionrestore', 12:17:09 INFO - 'sessionrestore_no_auto_restore', 12:17:09 INFO - 'tabpaint']}, 12:17:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:17:09 INFO - 'ts_paint', 12:17:09 INFO - 'tpaint', 12:17:09 INFO - 'sessionrestore', 12:17:09 INFO - 'sessionrestore_no_auto_restore', 12:17:09 INFO - 'tabpaint']}, 12:17:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:17:09 INFO - 'ts_paint', 12:17:09 INFO - 'tpaint', 12:17:09 INFO - 'sessionrestore', 12:17:09 INFO - 'sessionrestore_no_auto_restore', 12:17:09 INFO - 'tabpaint']}, 12:17:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['a11yr', 12:17:09 INFO - 'ts_paint', 12:17:09 INFO - 'tpaint', 12:17:09 INFO - 'sessionrestore', 12:17:09 INFO - 'sessionrestore_no_auto_restore', 12:17:09 INFO - 'tabpaint']}, 12:17:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['a11yr', 12:17:09 INFO - 'ts_paint', 12:17:09 INFO - 'tpaint', 12:17:09 INFO - 'sessionrestore', 12:17:09 INFO - 'sessionrestore_no_auto_restore', 12:17:09 INFO - 'tabpaint']}, 12:17:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['tsvgx', 12:17:09 INFO - 'tsvgr_opacity', 12:17:09 INFO - 'tart', 12:17:09 INFO - 'tscrollx', 12:17:09 INFO - 'cart']}, 12:17:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:17:09 INFO - 'tsvgr_opacity', 12:17:09 INFO - 'tart', 12:17:09 INFO - 'tscrollx', 12:17:09 INFO - 'cart']}, 12:17:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'talos_options': ['--disable-e10s'], 12:17:09 INFO - 'tests': ['tp5o']}, 12:17:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'tests': ['tp5o']}, 12:17:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'talos_options': ['--disable-e10s', 12:17:09 INFO - '--xperf_path', 12:17:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:09 INFO - 'tests': ['tp5n']}, 12:17:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:09 INFO - 'talos_options': ['--xperf_path', 12:17:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:09 INFO - 'tests': ['tp5n']}}, 12:17:09 INFO - 'talos.zip': {'path': '', 12:17:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:17:09 INFO - Running post-action listener: _resource_record_post_action 12:17:09 INFO - ##### 12:17:09 INFO - ##### Finished populate-webroot step (success) 12:17:09 INFO - ##### 12:17:09 INFO - ##### 12:17:09 INFO - ##### Running create-virtualenv step. 12:17:09 INFO - ##### 12:17:09 INFO - Running pre-action listener: _resource_record_pre_action 12:17:09 INFO - Running main action method: create_virtualenv 12:17:09 INFO - Creating virtualenv /builds/slave/test/build/venv 12:17:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:17:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:17:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:17:09 INFO - Using real prefix '/tools/python27' 12:17:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:17:10 INFO - Installing distribute.............................................................................................................................................................................................done. 12:17:13 INFO - Installing pip.................done. 12:17:13 INFO - Return code: 0 12:17:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:17: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')]} 12:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17: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')]} 12:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:13 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:13 INFO - 'HOME': '/Users/cltbld', 12:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:13 INFO - 'LOGNAME': 'cltbld', 12:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:13 INFO - 'MOZ_NO_REMOTE': '1', 12:17:13 INFO - 'NO_EM_RESTART': '1', 12:17:13 INFO - 'PAGER': '/bin/cat', 12:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:13 INFO - 'PWD': '/builds/slave/test', 12:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:13 INFO - 'SHELL': '/bin/bash', 12:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:13 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:13 INFO - 'USER': 'cltbld', 12:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:13 INFO - 'XPC_FLAGS': '0x0', 12:17:13 INFO - 'XPC_SERVICE_NAME': '0', 12:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:17:13 INFO - Cleaning up... 12:17:13 INFO - Return code: 0 12:17:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:17: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')]} 12:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17: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')]} 12:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:13 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:13 INFO - 'HOME': '/Users/cltbld', 12:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:13 INFO - 'LOGNAME': 'cltbld', 12:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:13 INFO - 'MOZ_NO_REMOTE': '1', 12:17:13 INFO - 'NO_EM_RESTART': '1', 12:17:13 INFO - 'PAGER': '/bin/cat', 12:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:13 INFO - 'PWD': '/builds/slave/test', 12:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:13 INFO - 'SHELL': '/bin/bash', 12:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:13 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:13 INFO - 'USER': 'cltbld', 12:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:13 INFO - 'XPC_FLAGS': '0x0', 12:17:13 INFO - 'XPC_SERVICE_NAME': '0', 12:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:13 INFO - Downloading/unpacking psutil>=3.1.1 12:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:17:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:17:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:17:16 INFO - Installing collected packages: psutil 12:17:16 INFO - Running setup.py install for psutil 12:17:16 INFO - building 'psutil._psutil_osx' extension 12:17:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:17:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:17:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:17:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:17:16 INFO - building 'psutil._psutil_posix' extension 12:17:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:17:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:17:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:17:16 INFO - ^ 12:17:16 INFO - 1 warning generated. 12:17:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:17:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:17:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:17:16 INFO - Successfully installed psutil 12:17:16 INFO - Cleaning up... 12:17:17 INFO - Return code: 0 12:17:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:17 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:17 INFO - 'HOME': '/Users/cltbld', 12:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:17 INFO - 'LOGNAME': 'cltbld', 12:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:17 INFO - 'MOZ_NO_REMOTE': '1', 12:17:17 INFO - 'NO_EM_RESTART': '1', 12:17:17 INFO - 'PAGER': '/bin/cat', 12:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:17 INFO - 'PWD': '/builds/slave/test', 12:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:17 INFO - 'SHELL': '/bin/bash', 12:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:17 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:17 INFO - 'USER': 'cltbld', 12:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:17 INFO - 'XPC_FLAGS': '0x0', 12:17:17 INFO - 'XPC_SERVICE_NAME': '0', 12:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:17:19 INFO - Installing collected packages: mozsystemmonitor 12:17:19 INFO - Running setup.py install for mozsystemmonitor 12:17:19 INFO - Successfully installed mozsystemmonitor 12:17:19 INFO - Cleaning up... 12:17:19 INFO - Return code: 0 12:17:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:19 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:17:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:19 INFO - 'HOME': '/Users/cltbld', 12:17:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:19 INFO - 'LOGNAME': 'cltbld', 12:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:19 INFO - 'MOZ_NO_REMOTE': '1', 12:17:19 INFO - 'NO_EM_RESTART': '1', 12:17:19 INFO - 'PAGER': '/bin/cat', 12:17:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:19 INFO - 'PWD': '/builds/slave/test', 12:17:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:19 INFO - 'SHELL': '/bin/bash', 12:17:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:19 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:19 INFO - 'USER': 'cltbld', 12:17:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:19 INFO - 'XPC_FLAGS': '0x0', 12:17:19 INFO - 'XPC_SERVICE_NAME': '0', 12:17:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:20 INFO - Downloading/unpacking blobuploader==1.2.4 12:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:22 INFO - Downloading blobuploader-1.2.4.tar.gz 12:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:17:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:17:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:23 INFO - Downloading docopt-0.6.1.tar.gz 12:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:17:23 INFO - Installing collected packages: blobuploader, requests, docopt 12:17:23 INFO - Running setup.py install for blobuploader 12:17:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:17:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:17:24 INFO - Running setup.py install for requests 12:17:24 INFO - Running setup.py install for docopt 12:17:24 INFO - Successfully installed blobuploader requests docopt 12:17:24 INFO - Cleaning up... 12:17:24 INFO - Return code: 0 12:17:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:24 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:24 INFO - 'HOME': '/Users/cltbld', 12:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:24 INFO - 'LOGNAME': 'cltbld', 12:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:24 INFO - 'MOZ_NO_REMOTE': '1', 12:17:24 INFO - 'NO_EM_RESTART': '1', 12:17:24 INFO - 'PAGER': '/bin/cat', 12:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:24 INFO - 'PWD': '/builds/slave/test', 12:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:24 INFO - 'SHELL': '/bin/bash', 12:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:24 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:24 INFO - 'USER': 'cltbld', 12:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:24 INFO - 'XPC_FLAGS': '0x0', 12:17:24 INFO - 'XPC_SERVICE_NAME': '0', 12:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-fQGe6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-rekBT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-T_AJ2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-pBwb3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-SKaNCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Rz8pJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-fL4L27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-9p52L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-LC0Bl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:17:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:17:25 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-emogSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-GVbVSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-8_T_Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-OTcpmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-NJ8Eja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Lmb2YD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-u_bBpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:17:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:17:26 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-MArSft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:17:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:17:26 INFO - Running setup.py install for manifestparser 12:17:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:17:26 INFO - Running setup.py install for mozcrash 12:17:27 INFO - Running setup.py install for mozdebug 12:17:27 INFO - Running setup.py install for mozdevice 12:17:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Running setup.py install for mozfile 12:17:27 INFO - Running setup.py install for mozhttpd 12:17:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Running setup.py install for mozinfo 12:17:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Running setup.py install for mozInstall 12:17:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:17:27 INFO - Running setup.py install for mozleak 12:17:28 INFO - Running setup.py install for mozlog 12:17:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:17:28 INFO - Running setup.py install for moznetwork 12:17:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:17:28 INFO - Running setup.py install for mozprocess 12:17:28 INFO - Running setup.py install for mozprofile 12:17:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:17:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:17:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:17:28 INFO - Running setup.py install for mozrunner 12:17:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:17:28 INFO - Running setup.py install for mozscreenshot 12:17:29 INFO - Running setup.py install for moztest 12:17:29 INFO - Running setup.py install for mozversion 12:17:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:17:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:17:29 INFO - Cleaning up... 12:17:29 INFO - Return code: 0 12:17:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:29 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:29 INFO - 'HOME': '/Users/cltbld', 12:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:29 INFO - 'LOGNAME': 'cltbld', 12:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:29 INFO - 'MOZ_NO_REMOTE': '1', 12:17:29 INFO - 'NO_EM_RESTART': '1', 12:17:29 INFO - 'PAGER': '/bin/cat', 12:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:29 INFO - 'PWD': '/builds/slave/test', 12:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:29 INFO - 'SHELL': '/bin/bash', 12:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:29 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:29 INFO - 'USER': 'cltbld', 12:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:29 INFO - 'XPC_FLAGS': '0x0', 12:17:29 INFO - 'XPC_SERVICE_NAME': '0', 12:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:17:29 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-fw6a5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:17:29 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-juIlDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:17:29 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-ysrG2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:17:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:17:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:17:29 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-vc6pw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-qO9g8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-9BSYVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-da2wRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-JwNgqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-muaxYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-XKUJ12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-yjGQ9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-LTs_bI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:17:30 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)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:17:30 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-qCCdmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:17:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:17:31 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-SMM_Nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:17:31 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)) 12:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:31 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-m8Vxf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:17:31 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-P898Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:17:31 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-NSufdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:17:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:34 INFO - Downloading blessings-1.6.tar.gz 12:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:17:34 INFO - Installing collected packages: blessings 12:17:34 INFO - Running setup.py install for blessings 12:17:34 INFO - Successfully installed blessings 12:17:34 INFO - Cleaning up... 12:17:34 INFO - Return code: 0 12:17:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:17:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:17:34 INFO - Reading from file tmpfile_stdout 12:17:34 INFO - Current package versions: 12:17:34 INFO - blessings == 1.6 12:17:34 INFO - blobuploader == 1.2.4 12:17:34 INFO - docopt == 0.6.1 12:17:34 INFO - manifestparser == 1.1 12:17:34 INFO - mozInstall == 1.12 12:17:34 INFO - mozcrash == 0.17 12:17:34 INFO - mozdebug == 0.1 12:17:34 INFO - mozdevice == 0.48 12:17:34 INFO - mozfile == 1.2 12:17:34 INFO - mozhttpd == 0.7 12:17:34 INFO - mozinfo == 0.9 12:17:34 INFO - mozleak == 0.1 12:17:34 INFO - mozlog == 3.2 12:17:34 INFO - moznetwork == 0.27 12:17:34 INFO - mozprocess == 0.22 12:17:34 INFO - mozprofile == 0.28 12:17:34 INFO - mozrunner == 6.11 12:17:34 INFO - mozscreenshot == 0.1 12:17:34 INFO - mozsystemmonitor == 0.3 12:17:34 INFO - moztest == 0.7 12:17:34 INFO - mozversion == 1.4 12:17:34 INFO - psutil == 3.1.1 12:17:34 INFO - requests == 1.2.3 12:17:34 INFO - wsgiref == 0.1.2 12:17:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:34 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:34 INFO - 'HOME': '/Users/cltbld', 12:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:34 INFO - 'LOGNAME': 'cltbld', 12:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:34 INFO - 'MOZ_NO_REMOTE': '1', 12:17:34 INFO - 'NO_EM_RESTART': '1', 12:17:34 INFO - 'PAGER': '/bin/cat', 12:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:34 INFO - 'PWD': '/builds/slave/test', 12:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:34 INFO - 'SHELL': '/bin/bash', 12:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:34 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:34 INFO - 'USER': 'cltbld', 12:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:34 INFO - 'XPC_FLAGS': '0x0', 12:17:34 INFO - 'XPC_SERVICE_NAME': '0', 12:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:17:34 INFO - Cleaning up... 12:17:34 INFO - Return code: 0 12:17:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:34 INFO - 'HOME': '/Users/cltbld', 12:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:34 INFO - 'LOGNAME': 'cltbld', 12:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:34 INFO - 'MOZ_NO_REMOTE': '1', 12:17:34 INFO - 'NO_EM_RESTART': '1', 12:17:34 INFO - 'PAGER': '/bin/cat', 12:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:34 INFO - 'PWD': '/builds/slave/test', 12:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:34 INFO - 'SHELL': '/bin/bash', 12:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:34 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:34 INFO - 'USER': 'cltbld', 12:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:34 INFO - 'XPC_FLAGS': '0x0', 12:17:34 INFO - 'XPC_SERVICE_NAME': '0', 12:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:35 INFO - Downloading/unpacking jsonschema 12:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:17:37 INFO - Downloading/unpacking functools32 (from jsonschema) 12:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:38 INFO - Downloading functools32-3.2.3-2.tar.gz 12:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:17:38 INFO - warning: no files found matching '*.txt' 12:17:38 INFO - no previously-included directories found matching 'build' 12:17:38 INFO - no previously-included directories found matching 'dist' 12:17:38 INFO - no previously-included directories found matching '.git*' 12:17:38 INFO - Installing collected packages: jsonschema, functools32 12:17:38 INFO - Running setup.py install for functools32 12:17:38 INFO - warning: no files found matching '*.txt' 12:17:38 INFO - no previously-included directories found matching 'build' 12:17:38 INFO - no previously-included directories found matching 'dist' 12:17:38 INFO - no previously-included directories found matching '.git*' 12:17:38 INFO - Successfully installed jsonschema functools32 12:17:38 INFO - Cleaning up... 12:17:38 INFO - Return code: 0 12:17:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:38 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 0x106bdba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106be1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faff9d80d00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, '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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:38 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 12:17:38 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 12:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:38 INFO - 'HOME': '/Users/cltbld', 12:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:38 INFO - 'LOGNAME': 'cltbld', 12:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:38 INFO - 'MOZ_NO_REMOTE': '1', 12:17:38 INFO - 'NO_EM_RESTART': '1', 12:17:38 INFO - 'PAGER': '/bin/cat', 12:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:38 INFO - 'PWD': '/builds/slave/test', 12:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:38 INFO - 'SHELL': '/bin/bash', 12:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:17:38 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:38 INFO - 'USER': 'cltbld', 12:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:38 INFO - 'XPC_FLAGS': '0x0', 12:17:38 INFO - 'XPC_SERVICE_NAME': '0', 12:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:38 INFO - Downloading/unpacking flake8 12:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:17:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:17:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:17:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:17:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:17:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:17:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:17:42 INFO - no previously-included directories found matching 'docs/_build' 12:17:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:17:42 INFO - Running setup.py install for pep8 12:17:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:17:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:17:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:17:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:17:42 INFO - no previously-included directories found matching 'docs/_build' 12:17:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:17:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:17:42 INFO - Cleaning up... 12:17:42 INFO - Return code: 0 12:17:42 INFO - Running post-action listener: _resource_record_post_action 12:17:42 INFO - Running post-action listener: _start_resource_monitoring 12:17:42 INFO - Starting resource monitoring. 12:17:42 INFO - ##### 12:17:42 INFO - ##### Finished create-virtualenv step (success) 12:17:42 INFO - ##### 12:17:42 INFO - ##### 12:17:42 INFO - ##### Running install step. 12:17:42 INFO - ##### 12:17:42 INFO - Running pre-action listener: _resource_record_pre_action 12:17:42 INFO - Running main action method: install 12:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:17:42 INFO - Reading from file tmpfile_stdout 12:17:42 INFO - Detecting whether we're running mozinstall >=1.0... 12:17:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:17:43 INFO - Reading from file tmpfile_stdout 12:17:43 INFO - Output received: 12:17:43 INFO - Usage: mozinstall [options] installer 12:17:43 INFO - Options: 12:17:43 INFO - -h, --help show this help message and exit 12:17:43 INFO - -d DEST, --destination=DEST 12:17:43 INFO - Directory to install application into. [default: 12:17:43 INFO - "/builds/slave/test"] 12:17:43 INFO - --app=APP Application being installed. [default: firefox] 12:17:43 INFO - mkdir: /builds/slave/test/build/application 12:17:43 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'] 12:17:43 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 12:18:16 INFO - Reading from file tmpfile_stdout 12:18:16 INFO - Output received: 12:18:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:18:16 INFO - Running post-action listener: _resource_record_post_action 12:18:16 INFO - ##### 12:18:16 INFO - ##### Finished install step (success) 12:18:16 INFO - ##### 12:18:16 INFO - ##### 12:18:16 INFO - ##### Running run-tests step. 12:18:16 INFO - ##### 12:18:16 INFO - Running pre-action listener: _resource_record_pre_action 12:18:16 INFO - Running main action method: run_tests 12:18:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:16 INFO - 2016-05-16 12:18:16.285 screenresolution[1920:14184] starting screenresolution argv=screenresolution get 12:18:16 INFO - 2016-05-16 12:18:16.287 screenresolution[1920:14184] Display 0: 1600x1200x32@60 12:18:16 INFO - 2016-05-16 12:18:16.293 screenresolution[1921:14186] starting screenresolution argv=screenresolution list 12:18:16 INFO - Available Modes on Display 0 12:18:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:16 INFO - Intel Iris: 12:18:16 INFO - Chipset Model: Intel Iris 12:18:16 INFO - Type: GPU 12:18:16 INFO - Bus: Built-In 12:18:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:16 INFO - Vendor: Intel (0x8086) 12:18:16 INFO - Device ID: 0x0a2e 12:18:16 INFO - Revision ID: 0x0009 12:18:16 INFO - Displays: 12:18:16 INFO - Display: 12:18:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:16 INFO - Display Serial Number: 1600x1200 60 12:18:16 INFO - Main Display: Yes 12:18:16 INFO - Mirror: Off 12:18:16 INFO - Online: Yes 12:18:16 INFO - Rotation: Supported 12:18:16 INFO - Return code: 0 12:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:18:16 INFO - Python 2.7.3 12:18:16 INFO - Return code: 0 12:18:16 INFO - grabbing minidump binary from tooltool 12:18: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')]} 12:18:16 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 0x106a7fd30>, '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 0x1068a3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106a76b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:18:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:18:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:18:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:18:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:18:16 INFO - Return code: 0 12:18:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:18:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:18:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:18:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:18:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:18:17 INFO - Return code: 0 12:18:17 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/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:18:17 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/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QbCl5Ho5Tj/Render', 12:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:17 INFO - 'HOME': '/Users/cltbld', 12:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:17 INFO - 'LOGNAME': 'cltbld', 12:18:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:17 INFO - 'MOZ_NO_REMOTE': '1', 12:18:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:17 INFO - 'NO_EM_RESTART': '1', 12:18:17 INFO - 'PAGER': '/bin/cat', 12:18:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:17 INFO - 'PWD': '/builds/slave/test', 12:18:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:17 INFO - 'SHELL': '/bin/bash', 12:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NYO1fgAJqD/Listeners', 12:18:17 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 12:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:17 INFO - 'USER': 'cltbld', 12:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:17 INFO - 'XPC_FLAGS': '0x0', 12:18:17 INFO - 'XPC_SERVICE_NAME': '0', 12:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:17 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/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:18:17 INFO - mozversion application_buildid: 20160516100443 12:18:17 INFO - mozversion application_changeset: 133ef863766e96786f6147f008cb4b95867857f0 12:18:17 INFO - mozversion application_display_name: Nightly 12:18:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:18:17 INFO - mozversion application_name: Firefox 12:18:17 INFO - mozversion application_remotingname: firefox 12:18:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:18:17 INFO - mozversion application_vendor: Mozilla 12:18:17 INFO - mozversion application_version: 49.0a1 12:18:17 INFO - mozversion platform_buildid: 20160516100443 12:18:17 INFO - mozversion platform_changeset: 133ef863766e96786f6147f008cb4b95867857f0 12:18:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:18:17 INFO - mozversion platform_version: 49.0a1 12:18:17 INFO - using testdate: 1463426297 12:18:17 INFO - actual date: 1463426297 12:18:17 INFO - starting webserver on 'localhost:52488' 12:18:17 INFO - SUITE-START | Running 2 tests 12:18:17 INFO - TEST-START | dromaeo_css 12:18:17 INFO - operating with platform_type : mac_ 12:18:17 INFO - Initialising browser for dromaeo_css test... 12:18:17 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpc_iKav/profile http://localhost:52488/getInfo.html) 12:18:20 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 12:18:20 INFO - PROCESS | 1939 | colorDepth:24 12:18:20 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 12:18:20 INFO - PROCESS | 1939 | __metrics 12:18:20 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:18:20 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:18:20 INFO - TEST-INFO | 1939: exit 0 12:18:20 INFO - Browser initialized. 12:18:20 INFO - Running cycle 1/1 for dromaeo_css test... 12:18:20 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpc_iKav/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:32 INFO - PROCESS | 1941 | RSS: Main: 239083520 12:18:32 INFO - PROCESS | 1941 | 12:19:24 INFO - PROCESS | 1941 | 1463426364506 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:21:35 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52488/tests/dromaeo/cssquery-ext.html) 12:21:36 INFO - PROCESS | 1941 | RSS: Main: 414597120 12:21:36 INFO - PROCESS | 1941 | 12:24:39 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52488/tests/dromaeo/cssquery-ext.html (next: http://localhost:52488/tests/dromaeo/cssquery-jquery.html) 12:24:40 INFO - PROCESS | 1941 | RSS: Main: 376791040 12:24:40 INFO - PROCESS | 1941 | 12:27:42 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52488/tests/dromaeo/cssquery-mootools.html) 12:27:43 INFO - PROCESS | 1941 | RSS: Main: 371339264 12:27:43 INFO - PROCESS | 1941 | 12:30:45 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52488/tests/dromaeo/cssquery-prototype.html) 12:30:46 INFO - PROCESS | 1941 | RSS: Main: 369414144 12:30:46 INFO - PROCESS | 1941 | 12:33:49 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52488/tests/dromaeo/cssquery-yui.html) 12:33:50 INFO - PROCESS | 1941 | RSS: Main: 346673152 12:33:50 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52488/tests/dromaeo/cssquery-yui.html (next: http://localhost:52488/tests/dromaeo/cssquery-dojo.html) 12:36:53 INFO - PROCESS | 1941 | RSS: Main: 380518400 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | __start_tp_report 12:36:53 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 12:36:53 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 12:36:53 INFO - PROCESS | 1941 | |i|pagename|runs| 12:36:53 INFO - PROCESS | 1941 | |0;dojo.html;2713.8914443422264;3428.29719907419;3436.742244331676;3447.551818263625;3500.9474857877135;5603.915941260884;5625.353084395296;5629.746661400237;5674.063779476387;5702.600817942745;5660.462256085676;5708.257926469553;5726;5732.828015159552;5747.396523365049;5080.339555857733;5142.820001299955;5145.3311069560905;5173.30160428343;5213.791448342081;5201.141811601081;5204.297419848329;5215.139501982168;5219.321488206528;5237.633365664405;4288.421063156481;5097.541221290083;5113.335266415361;5116.667416617908;5121.769520371574;5165.896682066356;5192.948070519322;5203.9219411708855;5224.608154388398;5244.423113457517;5026.371703537058;5030.195168772979;5091.66904151229;5123.718195499246;5139.486051394868;4602.838900638461;5511.118221084607;5537.612367134302;5550.91673624896;5562.805301814457;5133.409657889353;6206.6586337751405;6207.844803879894;6222.408871157278;6231.875362492725;6083.391660833925;6155.846103847395;6165.568410211287;6250.249970003629;6264.624122552661;5737.770489180428;5819.563532735062;5833.88332233353;5835.183074369592;5841.123831425235;6242.781502647476;6278.089676996809;6279.309275979638;6295.905561416583;6312.179416675803;5958.433948774859;5995.580309378389;6052.455279024821;6144.723487443076;6170.969145154246;6201.131841542187;6269.247690277196;6283.748650054038;6284.4344009039405;6297.716602752887;5110.437851836295;6184.845378865564;6190.907136392958;6191.164192834;6193.907091393633;6049.818505444844;6078.817635470855;6113.938860611426;6198.907016394758;6199.907001394983;4829.903401932012;4863.756812159378;4877.878053048702;4881.145799485076;4881.316615673809;4361.738295702268;4399.318105693623;4405.581469760367;4424.911501769947;4459.241928872099;5651.519620832275;5687.800926967537;5691.544676425876;5693.231413759172;5731.455511726379;6230.7819226327765;6287.717052732638;6296.181496405438;6298.307186209513;6302.527310451643;5565.053940830068;5609.887802243932;5631.71841407928;5660.320761508646;5691.715414229272;5338.145896656516;5357.330333708286;5358.27663265462;5364.490373414519;5398.8920221595345;6251.874962500816;6274.9686251569365;6276.152719382825;6300.842478938063;6307.3377295383325;6151.923413402547;6211.409916057967;6275.843103922256;6286.77407905454;6291.622502649856;6290.779822706183;6308.558400912076;6309.053641953744;6319.557630965788;6330.461910737455;6160.722767475385;6203.751849925952;6212.347703491068;6217.533684973736;6222.471089957405;6102.398363691488;6130.765048853506;6222.595531290452;6261.56169068179;6276.027215781562;6097.675918346994;6108.389161083856;6125.876495711566;6126.30393285589;6132.202813634199;5945.256842894638;6104.11490333908;6130.570860039933;6142.293636231891;6162.969185154046;5795.76816927335;5863;5863.794767183128;5940.435658612425;5962.433568810956;6077;6101.969490152521;6120.632762034292;6123.050927106127;6125.264968203844;5936.851578710567;5938.524918006463;5946.048632218824;5946.226990491208;5955.225820643289 12:36:53 INFO - PROCESS | 1941 | |1;ext.html;534.7299613695084;536.4528181255117;537.2612657595806;586.0886321769648;594.753177431366;25320.620190697133;42437.36343954839;43710;43944.78027609857;45701.62895113144;36444;44760.776196119055;45230.19079236835;45270.547294527045;45412.772936135276;8664.956675216616;9704.029597040293;9824.508774561244;9843;9851;8758.56207189638;8769.210771030606;8793.428427152247;8801.95599022004;11781.410929453494;11638;11838.7632247355;11904.64286071415;11906.702332441671;11927.045836333073;11945.223560468585;12023;12043.0365570755;12067.517299308018;12095.637130886043;8666.956665216696;11590.884091159065;11845.23006004607;11898.22661526998;11912.642620721306;41530.37704434436;41835.581644183476;41919;41977.79011104955;42190.367144492855;3228.418884600771;3232.6605706400724;3265.689759472846;3276.7050965413227;3426.3832510148163;28540.429191416057;29313.974010909515;29877.850610747024;30191;30463.543046854316;2592.831465954707;2699.9595006074924;2727.4408746207178;2730.5085084684747;2973.1972367460667;431966;526340;529575;530888;531016;67310.66344668245;68428.2364469949;68501.3149868495;68544;68658.65670671615;56108.438915611136;61800;67677;70717;70802.2919770806;16403.753943690856;16956.576085597713;16972.575685607713;16993.91503042477;17003.91498042502;12858;12933.805992910115;12939.482420703067;12948.611541653765;12952.158109722837;17501.82498175002;17906.55233619144;19788.90105549463;20373.796262037486;20412.489687757923;8282.213189746964;8298.543580103153;8302.460340077876;8309.667613295522;8582.227599516074;941.6374695742052;957.3729207369184;985.9457729824785;1121.698829357941;1123.0959077942334;30355.544666830014;30428;30638.80716102302;30655.69344306573;30920.69079309223;1104.729341311384;1120.9495572699248;1127.9548818047351;1151.9366434845997;1175.1127898436625;13981.720365592631;15569.844301557067;15678.608034799221;15763.369465221493;16168.514944551682;680038;690304;693265;694381;694471;62174.378256217766;62613;62674.37325626685;62680.686596566724;62745;1339.0492750147505;1375.9793603096161;1461.335092532895;1465.4284828916732;1544.3899659634158;29964.550531742476;30241.243968900955;30358.089257322317;31213.687863121988;32126;54029.189562155814;54697.72651136878;55042;55060.72469637626;55094.44905550893;635384;651115;653236.7338163279;653667;654459;42979;43570.34644480272;43758.9060273483;44651.55348446474;44769.552304476536;47181;47911.76044119757;48180.27729584129;48369.7907552314;49696;34361.82819085888;38470.615293847826;38815.80592097021;39609;40796.1840763189;35925.82037089798;38312.61687383202;38669.61330386773;39259.80370098131;39576.60423395729;38442.231155377296;39241.82274531769;39244.80377598094;39409.605903940595;39695.81673008012;32748;34525.65474345325;34533.13667158341;34697.826510867286;34839;19786.90106549458;20299.492512687302;22084.564503307232;24376.659283739566;25400.618990715524 12:36:53 INFO - PROCESS | 1941 | |2;jquery.html;385.1296070879812;389.28371795895555;393.3863173449421;397.1936967955052;417.768138683031;20153.798462015377;20387.08258128384;21446.463838404048;21460.57078858422;21483.46291342717;19430.70853937192;19710.507237319074;20535.38393848183;20539.973001349947;20592.794072059274;5101.464346243647;5481.342238931314;5494.313210848644;5511.476409741068;5528.226048353234;5446.836594902139;5487.478689524489;6037.547183961198;6166.506679465654;6170.105334726462;6107.4808641265545;6128.295246046695;6140.815775526741;6144.2012538369945;6156.846078848042;5384.1923711443205;5597.412271711472;5598.216249725042;5606.971965140148;5845.503132233765;5195.480451954812;5412.56699464042;5444.482774136491;5445.863853403657;5453.263809385722;14059.226742529157;14080.507182248675;21309.36071917845;26082;26726.599101013308;1915.2530513099764;2101.53766171442;2232.5423288225875;2261.027758064032;2325.4418939454577;8446.282066024396;9808.509574521317;10152.746181345452;10190.082892539707;14927.328270227757;1632.5347276026275;1684.6045858680402;1705.3519662528283;1739.6781595404764;1781.7950935642436;126211.36894315561;133398.33300833532;133696.6630333694;134302;134312;10971.780564388668;11041.33751974884;11110.333379997226;11686.532538698357;11990.400479975966;15340.923295383674;15404.306806193748;15413.460528881435;15429.922850385901;15463.072215667096;8490.320774338039;8646.43798153118;8683.826323473495;8782.165694259034;8787.769712240291;6420.133282006963;7204;8389;8436.98756149266;8480.575971201415;3728.5339332583426;4651.3720647712125;4663.323818046432;4664.277037059262;4730.290456431494;1924.4322924737237;1983.7123617075442;1985.960280794376;2001.1495114576246;2009.7889721579024;865.536937738307;902.8600566912139;942.8821397325335;974.4202199691173;1039.365986748083;14476.782848257284;19119.904400478186;20737;20859.8527081012;20899.268525601532;953.1612181280567;961.6682244625594;978.2369751593768;991.5389343955095;1021.667957913681;10526.631567895087;10973.50619222137;11054.723631909106;11056.668299951014;11070.39112848801;472850;580215;608218;621776;628555;135088;143806.28096859658;151086;154191;157027.21486392722;1009.8788145422596;1033.4109557552124;1050.6131905884313;1060.3002018667642;1060.7295139739317;18657.440276791716;18812.623747525246;18911.432657020312;18914;18950.24199032023;39268.60731392649;51409;55653.886922262136;57649.71175144098;58007;42145.78927105345;80785;82985.58507207426;85058.72411913703;86528;8153.3477321812925;8298.958505207434;8369.581520924052;8377.916220837713;8681.826363472568;12156.513739450676;12839.165454245443;12909.54816581415;13055.34723263852;13349.265790381622;11075.390853503135;11280.928311810365;11455.541778329107;11601.709957251136;11702.70743231426;10914.56341746353;11113.83329250078;11295.774084518427;11378.943105284421;11754.647360579196;10692.305000174963;11571.537138514703;11878.762424751629;11956.521739130338;11985.101117416403;8234.547099909563;8259.669613215405;8337.08292087882;8513.872291915503;8523.48859068458;10457.9477102614;11186.32882027081;11462.65612031607;11499.482523286639;11552.59565915189 12:36:53 INFO - PROCESS | 1941 | |3;mootools.html;1427.2078996157143;1597.5526852481305;1625.2280166920712;1889.2726300374352;2006.021058727789;7831.216878312166;8677.34919881009;8895.911040889589;8906.421082629624;9022.142896424846;8627.870581941275;8853.601587928542;8856.91143088569;8859.911400885989;8867.113288671146;2406.4104294447884;2436.2812970173754;2436.658867758515;2438.85366877987;2441.4628781668002;2392.162743039931;2422.479166979108;2432.671589335435;2438.317271164077;2444.6577479152934;2393.617021276596;2410.048031027743;2418.141559746291;2434.5982912819363;2438.4879175373194;2002.6395248855201;2370.8458950168183;2395.125779090628;2401.2436082633913;2415.867127307997;2290.404494831356;2389.139909632531;2392.7607239275935;2393.748656391071;2396.3769419950763;7812;8035.397345199075;8863.867041994376;8936.955315223448;8943.195112439847;5142.742862856842;5310.8141215057285;5328.360596728378;5354.598405119593;5363.115086010804;3448.1724386147143;3528.1708798432346;3597.406427939413;3619.7285203609836;3637.7635453695425;1038.2939601071268;1053.425882893817;1058.4919238765358;1059.1950117910367;1060.1465820014885;11596.246243994114;11809.23239989398;11823.290602563788;11838.408079595985;11839.822402663967;4714.622830173578;4822.807087716452;4945.802167913315;4950.25746138075;4982.676126051795;10218.693439196835;10221;10225.74435639115;10238.948805255926;10255.897441025643;5415.620906536584;5434.538064264581;5435.565154787608;5445.945540544544;5448.991936491761;4779.163646361872;4872.049950259665;4959.578435832923;4974.253861920741;4977.0294792515115;3835.7506762060375;3853.190829925691;3911.2959667259597;3966.7619942803526;3979.840806367713;1275.3495717184303;1282.3011458755002;1289.7227096174367;1300.473308310136;1301.6290357248138;804.6620419423856;886.7206829848577;888.8711933070979;896.050186801992;954.2938225712923;5699.601027928005;5708.4862362387585;5796.62321949072;5866.325372582122;5984.593620499179;448.8272015274078;464.1992562829093;470.5764811669525;470.7363876229322;475.2135216217166;3292.6378098409155;3929.410588411704;3983.1237127831832;4005.4792876925812;4129.772862492592;10637.042666160083;10661.147108231324;10865.619703310345;10903.890961090447;10920.781584368428;10660.786784264272;10881.673549793519;10888.40113793749;10894.291871028357;10921.12630989519;7801.492902961403;8097.838043239102;8187.222213889671;8196.754097377208;8233.259006689426;6010.2186715726675;6035;6105.511559075175;6156.107364432221;6173.938260617337;9753.707388778348;10141.645042423776;10260.794784104426;10277.486125693835;10289.3311934724;9746.951265243628;10179.083882450615;10360.274780765274;10360.326578772356;10392.688219353431;7507.737229196952;7596.81007974805;7627.771166865003;7701.229877012478;7709.0749110107145;8230.876536852073;8364.079951205242;8370.832583348178;8372.41393102477;8373.748787536384;7426.851462970602;7557.811054723676;7587.620618968919;7703.614819258903;7763.883541746765;7244.855102898018;7418.814529636802;7483.139438964371;7510.399168066432;7582.431317651088;7095.467839911924;7206.387457066208;7218.9893414923;7219.314165154302;7223.241559636373;4101.384792281182;4763.595094416874;4889.731064791471;4933.703977761345;5164.8192313268855;4073.654052763557;4959.82640607577;5058.822941197041;5079.64442489037;5251.36983561975 12:36:53 INFO - PROCESS | 1941 | |4;prototype.html;293.3546198363599;299.7422216893473;309.9693519049162;312.3440774373815;313.6753460168726;4310.374995625632;4783.617310615151;4991.6256280779;5190.896182076361;5230.581553475722;4144.41978123063;4433.977830110853;4663.300504924255;4940.925886111711;5137.948620513794;3037.3598256941227;3936.389859571771;3967.920641587181;3975.3166612536493;3980.7810570418615;3383.424817780971;3412.5326109772786;3745.906352341186;3880.825362858678;3988.840446382141;3446.775959562633;3845.9807700961455;3931.6461518463334;3933.960660393388;3967.900802479947;3563.322968635977;3653.634636536365;3905.7656540607372;3918.784466854322;3934.862279820207;3450.3099380123826;3452.982735086334;3455.9654403455893;3464.2898205867987;3481.234128491728;3619.873304434332;4259.744415335059;4549.385832912581;4889.853304400874;4994.325766021577;4416.293393057128;4425.800838962255;4431.955680443219;4464.665150113722;4466.70966387187;4947.851564453073;5011.599072074226;5192.299039629639;5392.892142157174;5417.729113544307;3742.719296052807;3744.719146064056;3753.418220175877;3760.461688360373;3782.7541209821547;4732.810687572527;4996.575291100261;5347.518723314882;5531.806386776524;5587.329520457531;3276.950845737316;3648.945265821015;3660.450932360114;3668.669819716238;3699.075231192202;3558.697510711567;3739.6821270191795;3764.8494060237285;3841.5966323536186;3852.24881148179;3882.7575175943425;4243.299855523804;4388.846390376321;4422.955770442319;4483.394741709827;4443.022535042285;4500.424851302018;5105.361829771278;5145.07388670043;5346.144616861284;1143.348291473852;1163.3310846263264;1212.078820096733;1225.9570915017932;1311.6392991927296;792.1286584756837;798.7044793426353;807.3581502705364;820.8016296207485;820.9671613135407;3626.7461277710836;4015.3135683013206;4232.476308528927;4241.406203131564;4253.8723838284905;4048.291548978917;4098.7950602470355;4295.098029413795;4390.538993405647;4619.5611416915335;3852.691784657277;4073.653739432122;4169.144730594917;4207.242696314693;4214.6091789709935;2382.344855164809;2449.252977841775;2464.6056630938965;2774.8612569371853;2849;5145.536901678867;5268.6575372600655;5393.541548968382;5820.563457740686;5833.620814647034;5580.86047848807;5810.389909059488;5830.988309033377;5849.64902105875;5851.502622277069;5075.720835354017;5435.266239057677;5791.942080579224;5840.620359676607;5871.853203669857;4438.667099967451;5167.870803229949;5566.832995010156;5581.553475721851;5713.857153571194;4622.491525932212;4803.214135801157;5070.163423035158;5343.839684809462;5559.24950131727;5200.063988482109;5505.9174112387545;5546.864457184094;5842.123681447817;5873.324567674601;3806.2958352704836;3828.8659896903473;3832.827522761538;3839.8080095995647;3841.519810023747;4786.976065119652;5289.783349829638;5607.299087614048;5648.971755141198;5720.885582288414;4457.2645513489915;4550.453945526426;4898.877528061685;5178.766955487087;5497.36780270274;4924.753762311799;5106.438291787826;5203.505666961632;5393.741102425104;5541.307336582927;5394.838154855361;5397.8380648580605;5405.351357837085;5421.918671219856;5432.538234250133;4632.984651676532;5505.752241149238;5511.917321240105;5524.315083899449;5558.888822223614;4359.367891655756;4696.976515117402;4798.352222450064;4814.940713043125;5432.886258317115 12:36:53 INFO - PROCESS | 1941 | |5;yui.html;638.4923985431582;643.745720440426;650.6356440393383;676.577139287945;741.0269365272784;16819.41132060378;17584;17604.38384656537;17672.64654706905;17707.1146442678;15272.541823745278;16172.919135404307;16223.918880405581;16342.182890855469;16419.671606567892;742.5099434373315;922.6355589542144;927.9211267042276;932.2309094996621;932.724846170378;891.8617614269799;895.7984453497976;896.5114012863005;897.5198268926126;900.4282280751737;972.3193764364937;974.1038244814788;977.4623956823754;980.6371642492288;981.249344251648;972.8054389122133;973;974.1914211204684;974.6539978307669;974.9171320437771;711.8433944532195;713.7894321175265;713.8822094354425;714.5212707485997;716;14880.925595372062;15306.387744490206;15407;15490.30293636789;15499.690006199926;12135.514579416813;12322.053211127888;12414.131010829155;12588.748225035539;12804.359782010863;10054.748631284276;10237.283390162691;10243.692689219335;10473.214508911862;10581.73545661357;3432.742544309162;3465.289615628815;3467.1852114753015;3499.667531584521;3582.8387722552548;55181.448185518035;62912;77332.22667773363;85157.57421212917;86694;7895.763127106196;9474.95262523683;9528.19010384125;9652.469114198646;10063.282294931421;20726;20866.79133208679;20896.582068358548;20907.163713451595;20927.79072209289;7236.529625574321;7327.633618319062;7339.1926888043345;7416.184219735825;7482.289182527651;3888.6694630956686;3936.3111455495173;3941.3693808990424;3947.4670919426085;3952.209558088394;3565.108722819295;3667.2298817248675;3674.356987527172;3677.7793332400142;3680.9631903681156;641.1120597971842;660.3561527510639;662.9900551491731;680.829792551862;681.2914568848342;482.495791897468;502.639601457356;549.9312585926759;552.9861753456196;575.4791913318453;7642.579658118857;7933;7995.040595128622;8053.83892322162;8068.3948703847445;540.3542766394199;556.4157634483776;560.5095541401274;560.7728869807735;562.3420597900466;3741.43878418234;3762.454444105644;3790.22300428417;3828.8085595720104;3852.210296889131;90420.6436903447;91396;91812.54093729489;92073.07926920646;92928;66194.66902665456;71936.64031679912;76266.44360131033;77630.6118469404;79889.203877864;71135;71146.7983940724;71597.92603110957;71934.2806571938;72228.63885680538;7512.849743005;7558.2441755823975;7669.501482403626;7696.461247712607;7903.68385264583;44332.44836430709;45484;45751.542484574726;47478.52521474741;47534.28698569455;56674.7166264166;63140.73718525512;63860.68069659622;64028.35971640224;64104;9841.507924603598;9935.801283974424;10008.699739007841;10012.949935250277;10161.237907157141;10569.207309451667;10845.347313237184;10959.506822192861;10964.56141754321;11768.823467648162;10117.443540605338;10440;10582.788344232918;10622.946885265525;10669.199810014125;9773.169280610944;9965.451900145561;10201.54093065799;10560;10621.097206737513;8975.820483590422;9290.442573445614;10027.04743049409;10269.178465722875;10291.639792607222;8765.386422950332;8823.382363234512;8859.778505537155;8899.911000889908;8903.73288801337;7373.188949215475;7889.960550197212;8151.225633564802;8231.876521852297;8332.291755200877 12:36:53 INFO - PROCESS | 1941 | __end_tp_report 12:36:53 INFO - PROCESS | 1941 | __start_cc_report 12:36:53 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1731 12:36:53 INFO - PROCESS | 1941 | __end_cc_report 12:36:53 INFO - PROCESS | 1941 | __startTimestamp1463427413318__endTimestamp 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | ------- Summary: start ------- 12:36:53 INFO - PROCESS | 1941 | Number of tests: 6 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5696.29 Median:5940.44 stddev:668.45 (11.3%) stddev-sans-first:628.22 12:36:53 INFO - PROCESS | 1941 | Values: 2713.9 3428.3 3436.7 3447.6 3500.9 5603.9 5625.4 5629.7 5674.1 5702.6 5660.5 5708.3 5726.0 5732.8 5747.4 5080.3 5142.8 5145.3 5173.3 5213.8 5201.1 5204.3 5215.1 5219.3 5237.6 4288.4 5097.5 5113.3 5116.7 5121.8 5165.9 5192.9 5203.9 5224.6 5244.4 5026.4 5030.2 5091.7 5123.7 5139.5 4602.8 5511.1 5537.6 5550.9 5562.8 5133.4 6206.7 6207.8 6222.4 6231.9 6083.4 6155.8 6165.6 6250.2 6264.6 5737.8 5819.6 5833.9 5835.2 5841.1 6242.8 6278.1 6279.3 6295.9 6312.2 5958.4 5995.6 6052.5 6144.7 6171.0 6201.1 6269.2 6283.7 6284.4 6297.7 5110.4 6184.8 6190.9 6191.2 6193.9 6049.8 6078.8 6113.9 6198.9 6199.9 4829.9 4863.8 4877.9 4881.1 4881.3 4361.7 4399.3 4405.6 4424.9 4459.2 5651.5 5687.8 5691.5 5693.2 5731.5 6230.8 6287.7 6296.2 6298.3 6302.5 5565.1 5609.9 5631.7 5660.3 5691.7 5338.1 5357.3 5358.3 5364.5 5398.9 6251.9 6275.0 6276.2 6300.8 6307.3 6151.9 6211.4 6275.8 6286.8 6291.6 6290.8 6308.6 6309.1 6319.6 6330.5 6160.7 6203.8 6212.3 6217.5 6222.5 6102.4 6130.8 6222.6 6261.6 6276.0 6097.7 6108.4 6125.9 6126.3 6132.2 5945.3 6104.1 6130.6 6142.3 6163.0 5795.8 5863.0 5863.8 5940.4 5962.4 6077.0 6102.0 6120.6 6123.1 6125.3 5936.9 5938.5 5946.0 5946.2 5955.2 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:75541.95 Median:39028.81 stddev:166387.22 (426.3%) stddev-sans-first:166758.68 12:36:53 INFO - PROCESS | 1941 | Values: 534.7 536.5 537.3 586.1 594.8 25320.6 42437.4 43710.0 43944.8 45701.6 36444.0 44760.8 45230.2 45270.5 45412.8 8665.0 9704.0 9824.5 9843.0 9851.0 8758.6 8769.2 8793.4 8802.0 11781.4 11638.0 11838.8 11904.6 11906.7 11927.0 11945.2 12023.0 12043.0 12067.5 12095.6 8667.0 11590.9 11845.2 11898.2 11912.6 41530.4 41835.6 41919.0 41977.8 42190.4 3228.4 3232.7 3265.7 3276.7 3426.4 28540.4 29314.0 29877.9 30191.0 30463.5 2592.8 2700.0 2727.4 2730.5 2973.2 431966.0 526340.0 529575.0 530888.0 531016.0 67310.7 68428.2 68501.3 68544.0 68658.7 56108.4 61800.0 67677.0 70717.0 70802.3 16403.8 16956.6 16972.6 16993.9 17003.9 12858.0 12933.8 12939.5 12948.6 12952.2 17501.8 17906.6 19788.9 20373.8 20412.5 8282.2 8298.5 8302.5 8309.7 8582.2 941.6 957.4 985.9 1121.7 1123.1 30355.5 30428.0 30638.8 30655.7 30920.7 1104.7 1120.9 1128.0 1151.9 1175.1 13981.7 15569.8 15678.6 15763.4 16168.5 680038.0 690304.0 693265.0 694381.0 694471.0 62174.4 62613.0 62674.4 62680.7 62745.0 1339.0 1376.0 1461.3 1465.4 1544.4 29964.6 30241.2 30358.1 31213.7 32126.0 54029.2 54697.7 55042.0 55060.7 55094.4 635384.0 651115.0 653236.7 653667.0 654459.0 42979.0 43570.3 43758.9 44651.6 44769.6 47181.0 47911.8 48180.3 48369.8 49696.0 34361.8 38470.6 38815.8 39609.0 40796.2 35925.8 38312.6 38669.6 39259.8 39576.6 38442.2 39241.8 39244.8 39409.6 39695.8 32748.0 34525.7 34533.1 34697.8 34839.0 19786.9 20299.5 22084.6 24376.7 25400.6 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:35352.54 Median:11705.45 stddev:98808.40 (844.1%) stddev-sans-first:99050.70 12:36:53 INFO - PROCESS | 1941 | Values: 385.1 389.3 393.4 397.2 417.8 20153.8 20387.1 21446.5 21460.6 21483.5 19430.7 19710.5 20535.4 20540.0 20592.8 5101.5 5481.3 5494.3 5511.5 5528.2 5446.8 5487.5 6037.5 6166.5 6170.1 6107.5 6128.3 6140.8 6144.2 6156.8 5384.2 5597.4 5598.2 5607.0 5845.5 5195.5 5412.6 5444.5 5445.9 5453.3 14059.2 14080.5 21309.4 26082.0 26726.6 1915.3 2101.5 2232.5 2261.0 2325.4 8446.3 9808.5 10152.7 10190.1 14927.3 1632.5 1684.6 1705.4 1739.7 1781.8 126211.4 133398.3 133696.7 134302.0 134312.0 10971.8 11041.3 11110.3 11686.5 11990.4 15340.9 15404.3 15413.5 15429.9 15463.1 8490.3 8646.4 8683.8 8782.2 8787.8 6420.1 7204.0 8389.0 8437.0 8480.6 3728.5 4651.4 4663.3 4664.3 4730.3 1924.4 1983.7 1986.0 2001.1 2009.8 865.5 902.9 942.9 974.4 1039.4 14476.8 19119.9 20737.0 20859.9 20899.3 953.2 961.7 978.2 991.5 1021.7 10526.6 10973.5 11054.7 11056.7 11070.4 472850.0 580215.0 608218.0 621776.0 628555.0 135088.0 143806.3 151086.0 154191.0 157027.2 1009.9 1033.4 1050.6 1060.3 1060.7 18657.4 18812.6 18911.4 18914.0 18950.2 39268.6 51409.0 55653.9 57649.7 58007.0 42145.8 80785.0 82985.6 85058.7 86528.0 8153.3 8299.0 8369.6 8377.9 8681.8 12156.5 12839.2 12909.5 13055.3 13349.3 11075.4 11280.9 11455.5 11601.7 11702.7 10914.6 11113.8 11295.8 11378.9 11754.6 10692.3 11571.5 11878.8 11956.5 11985.1 8234.5 8259.7 8337.1 8513.9 8523.5 10457.9 11186.3 11462.7 11499.5 11552.6 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5753.98 Median:2619.40 stddev:3255.91 (124.3%) stddev-sans-first:3248.81 12:36:53 INFO - PROCESS | 1941 | Values: 1427.2 1597.6 1625.2 1889.3 2006.0 7831.2 8677.3 8895.9 8906.4 9022.1 8627.9 8853.6 8856.9 8859.9 8867.1 2406.4 2436.3 2436.7 2438.9 2441.5 2392.2 2422.5 2432.7 2438.3 2444.7 2393.6 2410.0 2418.1 2434.6 2438.5 2002.6 2370.8 2395.1 2401.2 2415.9 2290.4 2389.1 2392.8 2393.7 2396.4 7812.0 8035.4 8863.9 8937.0 8943.2 5142.7 5310.8 5328.4 5354.6 5363.1 3448.2 3528.2 3597.4 3619.7 3637.8 1038.3 1053.4 1058.5 1059.2 1060.1 11596.2 11809.2 11823.3 11838.4 11839.8 4714.6 4822.8 4945.8 4950.3 4982.7 10218.7 10221.0 10225.7 10238.9 10255.9 5415.6 5434.5 5435.6 5445.9 5449.0 4779.2 4872.0 4959.6 4974.3 4977.0 3835.8 3853.2 3911.3 3966.8 3979.8 1275.3 1282.3 1289.7 1300.5 1301.6 804.7 886.7 888.9 896.1 954.3 5699.6 5708.5 5796.6 5866.3 5984.6 448.8 464.2 470.6 470.7 475.2 3292.6 3929.4 3983.1 4005.5 4129.8 10637.0 10661.1 10865.6 10903.9 10920.8 10660.8 10881.7 10888.4 10894.3 10921.1 7801.5 8097.8 8187.2 8196.8 8233.3 6010.2 6035.0 6105.5 6156.1 6173.9 9753.7 10141.6 10260.8 10277.5 10289.3 9747.0 10179.1 10360.3 10360.3 10392.7 7507.7 7596.8 7627.8 7701.2 7709.1 8230.9 8364.1 8370.8 8372.4 8373.7 7426.9 7557.8 7587.6 7703.6 7763.9 7244.9 7418.8 7483.1 7510.4 7582.4 7095.5 7206.4 7219.0 7219.3 7223.2 4101.4 4763.6 4889.7 4933.7 5164.8 4073.7 4959.8 5058.8 5079.6 5251.4 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4248.02 Median:4465.69 stddev:1320.82 (29.6%) stddev-sans-first:1290.74 12:36:53 INFO - PROCESS | 1941 | Values: 293.4 299.7 310.0 312.3 313.7 4310.4 4783.6 4991.6 5190.9 5230.6 4144.4 4434.0 4663.3 4940.9 5137.9 3037.4 3936.4 3967.9 3975.3 3980.8 3383.4 3412.5 3745.9 3880.8 3988.8 3446.8 3846.0 3931.6 3934.0 3967.9 3563.3 3653.6 3905.8 3918.8 3934.9 3450.3 3453.0 3456.0 3464.3 3481.2 3619.9 4259.7 4549.4 4889.9 4994.3 4416.3 4425.8 4432.0 4464.7 4466.7 4947.9 5011.6 5192.3 5392.9 5417.7 3742.7 3744.7 3753.4 3760.5 3782.8 4732.8 4996.6 5347.5 5531.8 5587.3 3277.0 3648.9 3660.5 3668.7 3699.1 3558.7 3739.7 3764.8 3841.6 3852.2 3882.8 4243.3 4388.8 4423.0 4483.4 4443.0 4500.4 5105.4 5145.1 5346.1 1143.3 1163.3 1212.1 1226.0 1311.6 792.1 798.7 807.4 820.8 821.0 3626.7 4015.3 4232.5 4241.4 4253.9 4048.3 4098.8 4295.1 4390.5 4619.6 3852.7 4073.7 4169.1 4207.2 4214.6 2382.3 2449.3 2464.6 2774.9 2849.0 5145.5 5268.7 5393.5 5820.6 5833.6 5580.9 5810.4 5831.0 5849.6 5851.5 5075.7 5435.3 5791.9 5840.6 5871.9 4438.7 5167.9 5566.8 5581.6 5713.9 4622.5 4803.2 5070.2 5343.8 5559.2 5200.1 5505.9 5546.9 5842.1 5873.3 3806.3 3828.9 3832.8 3839.8 3841.5 4787.0 5289.8 5607.3 5649.0 5720.9 4457.3 4550.5 4898.9 5178.8 5497.4 4924.8 5106.4 5203.5 5393.7 5541.3 5394.8 5397.8 5405.4 5421.9 5432.5 4633.0 5505.8 5511.9 5524.3 5558.9 4359.4 4697.0 4798.4 4814.9 5432.9 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:17626.32 Median:63944.52 stddev:24780.82 (38.8%) stddev-sans-first:24817.51 12:36:53 INFO - PROCESS | 1941 | Values: 638.5 643.7 650.6 676.6 741.0 16819.4 17584.0 17604.4 17672.6 17707.1 15272.5 16172.9 16223.9 16342.2 16419.7 742.5 922.6 927.9 932.2 932.7 891.9 895.8 896.5 897.5 900.4 972.3 974.1 977.5 980.6 981.2 972.8 973.0 974.2 974.7 974.9 711.8 713.8 713.9 714.5 716.0 14880.9 15306.4 15407.0 15490.3 15499.7 12135.5 12322.1 12414.1 12588.7 12804.4 10054.7 10237.3 10243.7 10473.2 10581.7 3432.7 3465.3 3467.2 3499.7 3582.8 55181.4 62912.0 77332.2 85157.6 86694.0 7895.8 9475.0 9528.2 9652.5 10063.3 20726.0 20866.8 20896.6 20907.2 20927.8 7236.5 7327.6 7339.2 7416.2 7482.3 3888.7 3936.3 3941.4 3947.5 3952.2 3565.1 3667.2 3674.4 3677.8 3681.0 641.1 660.4 663.0 680.8 681.3 482.5 502.6 549.9 553.0 575.5 7642.6 7933.0 7995.0 8053.8 8068.4 540.4 556.4 560.5 560.8 562.3 3741.4 3762.5 3790.2 3828.8 3852.2 90420.6 91396.0 91812.5 92073.1 92928.0 66194.7 71936.6 76266.4 77630.6 79889.2 71135.0 71146.8 71597.9 71934.3 72228.6 7512.8 7558.2 7669.5 7696.5 7903.7 44332.4 45484.0 45751.5 47478.5 47534.3 56674.7 63140.7 63860.7 64028.4 64104.0 9841.5 9935.8 10008.7 10012.9 10161.2 10569.2 10845.3 10959.5 10964.6 11768.8 10117.4 10440.0 10582.8 10622.9 10669.2 9773.2 9965.5 10201.5 10560.0 10621.1 8975.8 9290.4 10027.0 10269.2 10291.6 8765.4 8823.4 8859.8 8899.9 8903.7 7373.2 7890.0 8151.2 8231.9 8332.3 12:36:53 INFO - PROCESS | 1941 | -------- Summary: end -------- 12:36:53 INFO - PROCESS | 1941 | 12:36:53 INFO - TEST-INFO | 1941: exit 0 12:36:53 INFO - TEST-OK | dromaeo_css | took 1115924ms 12:36:53 INFO - TEST-START | kraken 12:36:53 INFO - operating with platform_type : mac_ 12:36:53 INFO - Initialising browser for kraken test... 12:36:53 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpH5J38z/profile http://localhost:52488/getInfo.html) 12:36:56 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 12:36:56 INFO - PROCESS | 1945 | colorDepth:24 12:36:56 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 12:36:56 INFO - PROCESS | 1945 | __metrics 12:36:56 INFO - PROCESS | 1945 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:36:56 ERROR - PROCESS | 1945 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:36:57 INFO - TEST-INFO | 1945: exit 0 12:36:57 INFO - Browser initialized. 12:36:57 INFO - Running cycle 1/1 for kraken test... 12:36:57 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpH5J38z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:37:08 INFO - PROCESS | 1947 | RSS: Main: 239144960 12:37:08 INFO - PROCESS | 1947 | 12:37:11 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52488/tests/kraken/driver.html?testName=audio-beat-detection) 12:37:12 INFO - PROCESS | 1947 | RSS: Main: 287043584 12:37:12 INFO - PROCESS | 1947 | 12:37:15 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52488/tests/kraken/driver.html?testName=audio-dft) 12:37:16 INFO - PROCESS | 1947 | RSS: Main: 297955328 12:37:16 INFO - PROCESS | 1947 | 12:37:19 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52488/tests/kraken/driver.html?testName=audio-fft) 12:37:20 INFO - PROCESS | 1947 | RSS: Main: 383426560 12:37:20 INFO - PROCESS | 1947 | 12:37:22 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52488/tests/kraken/driver.html?testName=audio-oscillator) 12:37:23 INFO - PROCESS | 1947 | RSS: Main: 285061120 12:37:23 INFO - PROCESS | 1947 | 12:37:25 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:37:25 INFO - PROCESS | 1947 | RSS: Main: 288690176 12:37:25 INFO - PROCESS | 1947 | 12:37:28 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52488/tests/kraken/driver.html?testName=imaging-darkroom) 12:37:29 INFO - PROCESS | 1947 | RSS: Main: 324734976 12:37:29 INFO - PROCESS | 1947 | 12:37:32 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52488/tests/kraken/driver.html?testName=imaging-desaturate) 12:37:33 INFO - PROCESS | 1947 | RSS: Main: 326443008 12:37:33 INFO - PROCESS | 1947 | 12:37:36 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52488/tests/kraken/driver.html?testName=json-parse-financial) 12:37:36 INFO - PROCESS | 1947 | RSS: Main: 327237632 12:37:36 INFO - PROCESS | 1947 | 12:37:38 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:37:38 INFO - PROCESS | 1947 | RSS: Main: 316014592 12:37:38 INFO - PROCESS | 1947 | 12:37:40 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:37:41 INFO - PROCESS | 1947 | RSS: Main: 322207744 12:37:41 INFO - PROCESS | 1947 | 12:37:43 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:37:44 INFO - PROCESS | 1947 | RSS: Main: 324501504 12:37:44 INFO - PROCESS | 1947 | 12:37:47 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:37:48 INFO - PROCESS | 1947 | RSS: Main: 329277440 12:37:48 INFO - PROCESS | 1947 | 12:37:51 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:37:51 INFO - PROCESS | 1947 | RSS: Main: 326959104 12:37:51 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:52488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52488/tests/kraken/driver.html?testName=ai-astar) 12:37:53 INFO - PROCESS | 1947 | RSS: Main: 373981184 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | __start_tp_report 12:37:53 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 12:37:53 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 12:37:53 INFO - PROCESS | 1947 | |i|pagename|runs| 12:37:53 INFO - PROCESS | 1947 | |0;ai-astar;111;105;111;111;112;112;111;112;112;111 12:37:53 INFO - PROCESS | 1947 | |1;audio-beat-detection;160;124;127;239;146;164;139;116;169;138 12:37:53 INFO - PROCESS | 1947 | |2;audio-dft;185;186;185;184;185;184;182;184;183;180 12:37:53 INFO - PROCESS | 1947 | |3;audio-fft;77;77;56;124;56;60;58;73;134;80 12:37:53 INFO - PROCESS | 1947 | |4;audio-oscillator;96;96;134;78;84;77;101;81;79;100 12:37:53 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;88;88;87;84;87;83;89;84;87;84 12:37:53 INFO - PROCESS | 1947 | |6;imaging-darkroom;92;93;94;94;93;93;94;93;93;93 12:37:53 INFO - PROCESS | 1947 | |7;imaging-desaturate;85;85;84;84;86;85;85;85;86;88 12:37:53 INFO - PROCESS | 1947 | |8;json-parse-financial;58;58;60;54;55;55;56;118;58;56 12:37:53 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;63;70;71;73;70;69;67;69;68;67 12:37:53 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;86;85;86;85;88;97;98;86;86;87 12:37:53 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;157;152;128;137;125;172;142;141;220;137 12:37:53 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;166;163;163;165;162;189;220;181;189;218 12:37:53 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;67;66;66;66;66;66;65;65;66;64 12:37:53 INFO - PROCESS | 1947 | __end_tp_report 12:37:53 INFO - PROCESS | 1947 | __start_cc_report 12:37:53 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,2224 12:37:53 INFO - PROCESS | 1947 | __end_cc_report 12:37:53 INFO - PROCESS | 1947 | __startTimestamp1463427473957__endTimestamp 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | ------- Summary: start ------- 12:37:53 INFO - PROCESS | 1947 | Number of tests: 14 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:2.10 (1.9%) stddev-sans-first:2.22 12:37:53 INFO - PROCESS | 1947 | Values: 111.0 105.0 111.0 111.0 112.0 112.0 111.0 112.0 112.0 111.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:152.20 Median:153.00 stddev:35.23 (23.0%) stddev-sans-first:37.26 12:37:53 INFO - PROCESS | 1947 | Values: 160.0 124.0 127.0 239.0 146.0 164.0 139.0 116.0 169.0 138.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.75 (0.9%) stddev-sans-first:1.80 12:37:53 INFO - PROCESS | 1947 | Values: 185.0 186.0 185.0 184.0 185.0 184.0 182.0 184.0 183.0 180.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:79.50 Median:66.50 stddev:27.79 (41.8%) stddev-sans-first:29.47 12:37:53 INFO - PROCESS | 1947 | Values: 77.0 77.0 56.0 124.0 56.0 60.0 58.0 73.0 134.0 80.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:80.00 stddev:17.36 (21.7%) stddev-sans-first:18.37 12:37:53 INFO - PROCESS | 1947 | Values: 96.0 96.0 134.0 78.0 84.0 77.0 101.0 81.0 79.0 100.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:2.13 (2.5%) stddev-sans-first:2.15 12:37:53 INFO - PROCESS | 1947 | Values: 88.0 88.0 87.0 84.0 87.0 83.0 89.0 84.0 87.0 84.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 12:37:53 INFO - PROCESS | 1947 | Values: 92.0 93.0 94.0 94.0 93.0 93.0 94.0 93.0 93.0 93.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.22 12:37:53 INFO - PROCESS | 1947 | Values: 85.0 85.0 84.0 84.0 86.0 85.0 85.0 85.0 86.0 88.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:57.00 stddev:19.48 (34.2%) stddev-sans-first:20.59 12:37:53 INFO - PROCESS | 1947 | Values: 58.0 58.0 60.0 54.0 55.0 55.0 56.0 118.0 58.0 56.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.50 stddev:2.71 (3.9%) stddev-sans-first:1.94 12:37:53 INFO - PROCESS | 1947 | Values: 63.0 70.0 71.0 73.0 70.0 69.0 67.0 69.0 68.0 67.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:86.50 stddev:4.88 (5.6%) stddev-sans-first:5.10 12:37:53 INFO - PROCESS | 1947 | Values: 86.0 85.0 86.0 85.0 88.0 97.0 98.0 86.0 86.0 87.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:147.00 stddev:27.92 (19.0%) stddev-sans-first:29.53 12:37:53 INFO - PROCESS | 1947 | Values: 157.0 152.0 128.0 137.0 125.0 172.0 142.0 141.0 220.0 137.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:185.00 stddev:22.37 (12.1%) stddev-sans-first:23.01 12:37:53 INFO - PROCESS | 1947 | Values: 166.0 163.0 163.0 165.0 162.0 189.0 220.0 181.0 189.0 218.0 12:37:53 INFO - PROCESS | 1947 | 12:37:53 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.73 12:37:53 INFO - PROCESS | 1947 | Values: 67.0 66.0 66.0 66.0 66.0 66.0 65.0 65.0 66.0 64.0 12:37:53 INFO - PROCESS | 1947 | -------- Summary: end -------- 12:37:53 INFO - PROCESS | 1947 | 12:37:54 INFO - TEST-INFO | 1947: exit 0 12:37:54 INFO - TEST-OK | kraken | took 60917ms 12:37:54 INFO - SUITE-END | took 1176s 12:37:55 INFO - Completed test suite (00:19:37) 12:37:55 INFO - javascript benchmark 12:37:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2713.8914443422264, 3428.29719907419, 3436.742244331676, 3447.551818263625, 3500.9474857877135, 5603.915941260884, 5625.353084395296, 5629.746661400237, 5674.063779476387, 5702.600817942745, 5660.462256085676, 5708.257926469553, 5726.0, 5732.828015159552, 5747.396523365049, 5080.339555857733, 5142.820001299955, 5145.3311069560905, 5173.30160428343, 5213.791448342081, 5201.141811601081, 5204.297419848329, 5215.139501982168, 5219.321488206528, 5237.633365664405, 4288.421063156481, 5097.541221290083, 5113.335266415361, 5116.667416617908, 5121.769520371574, 5165.896682066356, 5192.948070519322, 5203.9219411708855, 5224.608154388398, 5244.423113457517, 5026.371703537058, 5030.195168772979, 5091.66904151229, 5123.718195499246, 5139.486051394868, 4602.838900638461, 5511.118221084607, 5537.612367134302, 5550.91673624896, 5562.805301814457, 5133.409657889353, 6206.6586337751405, 6207.844803879894, 6222.408871157278, 6231.875362492725, 6083.391660833925, 6155.846103847395, 6165.568410211287, 6250.249970003629, 6264.624122552661, 5737.770489180428, 5819.563532735062, 5833.88332233353, 5835.183074369592, 5841.123831425235, 6242.781502647476, 6278.089676996809, 6279.309275979638, 6295.905561416583, 6312.179416675803, 5958.433948774859, 5995.580309378389, 6052.455279024821, 6144.723487443076, 6170.969145154246, 6201.131841542187, 6269.247690277196, 6283.748650054038, 6284.4344009039405, 6297.716602752887, 5110.437851836295, 6184.845378865564, 6190.907136392958, 6191.164192834, 6193.907091393633, 6049.818505444844, 6078.817635470855, 6113.938860611426, 6198.907016394758, 6199.907001394983, 4829.903401932012, 4863.756812159378, 4877.878053048702, 4881.145799485076, 4881.316615673809, 4361.738295702268, 4399.318105693623, 4405.581469760367, 4424.911501769947, 4459.241928872099, 5651.519620832275, 5687.800926967537, 5691.544676425876, 5693.231413759172, 5731.455511726379, 6230.7819226327765, 6287.717052732638, 6296.181496405438, 6298.307186209513, 6302.527310451643, 5565.053940830068, 5609.887802243932, 5631.71841407928, 5660.320761508646, 5691.715414229272, 5338.145896656516, 5357.330333708286, 5358.27663265462, 5364.490373414519, 5398.8920221595345, 6251.874962500816, 6274.9686251569365, 6276.152719382825, 6300.842478938063, 6307.3377295383325, 6151.923413402547, 6211.409916057967, 6275.843103922256, 6286.77407905454, 6291.622502649856, 6290.779822706183, 6308.558400912076, 6309.053641953744, 6319.557630965788, 6330.461910737455, 6160.722767475385, 6203.751849925952, 6212.347703491068, 6217.533684973736, 6222.471089957405, 6102.398363691488, 6130.765048853506, 6222.595531290452, 6261.56169068179, 6276.027215781562, 6097.675918346994, 6108.389161083856, 6125.876495711566, 6126.30393285589, 6132.202813634199, 5945.256842894638, 6104.11490333908, 6130.570860039933, 6142.293636231891, 6162.969185154046, 5795.76816927335, 5863.0, 5863.794767183128, 5940.435658612425, 5962.433568810956, 6077.0, 6101.969490152521, 6120.632762034292, 6123.050927106127, 6125.264968203844, 5936.851578710567, 5938.524918006463, 5946.048632218824, 5946.226990491208, 5955.225820643289], "value": 5652.415349627248, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.7299613695084, 536.4528181255117, 537.2612657595806, 586.0886321769648, 594.753177431366, 25320.620190697133, 42437.36343954839, 43710.0, 43944.78027609857, 45701.62895113144, 36444.0, 44760.776196119055, 45230.19079236835, 45270.547294527045, 45412.772936135276, 8664.956675216616, 9704.029597040293, 9824.508774561244, 9843.0, 9851.0, 8758.56207189638, 8769.210771030606, 8793.428427152247, 8801.95599022004, 11781.410929453494, 11638.0, 11838.7632247355, 11904.64286071415, 11906.702332441671, 11927.045836333073, 11945.223560468585, 12023.0, 12043.0365570755, 12067.517299308018, 12095.637130886043, 8666.956665216696, 11590.884091159065, 11845.23006004607, 11898.22661526998, 11912.642620721306, 41530.37704434436, 41835.581644183476, 41919.0, 41977.79011104955, 42190.367144492855, 3228.418884600771, 3232.6605706400724, 3265.689759472846, 3276.7050965413227, 3426.3832510148163, 28540.429191416057, 29313.974010909515, 29877.850610747024, 30191.0, 30463.543046854316, 2592.831465954707, 2699.9595006074924, 2727.4408746207178, 2730.5085084684747, 2973.1972367460667, 431966.0, 526340.0, 529575.0, 530888.0, 531016.0, 67310.66344668245, 68428.2364469949, 68501.3149868495, 68544.0, 68658.65670671615, 56108.438915611136, 61800.0, 67677.0, 70717.0, 70802.2919770806, 16403.753943690856, 16956.576085597713, 16972.575685607713, 16993.91503042477, 17003.91498042502, 12858.0, 12933.805992910115, 12939.482420703067, 12948.611541653765, 12952.158109722837, 17501.82498175002, 17906.55233619144, 19788.90105549463, 20373.796262037486, 20412.489687757923, 8282.213189746964, 8298.543580103153, 8302.460340077876, 8309.667613295522, 8582.227599516074, 941.6374695742052, 957.3729207369184, 985.9457729824785, 1121.698829357941, 1123.0959077942334, 30355.544666830014, 30428.0, 30638.80716102302, 30655.69344306573, 30920.69079309223, 1104.729341311384, 1120.9495572699248, 1127.9548818047351, 1151.9366434845997, 1175.1127898436625, 13981.720365592631, 15569.844301557067, 15678.608034799221, 15763.369465221493, 16168.514944551682, 680038.0, 690304.0, 693265.0, 694381.0, 694471.0, 62174.378256217766, 62613.0, 62674.37325626685, 62680.686596566724, 62745.0, 1339.0492750147505, 1375.9793603096161, 1461.335092532895, 1465.4284828916732, 1544.3899659634158, 29964.550531742476, 30241.243968900955, 30358.089257322317, 31213.687863121988, 32126.0, 54029.189562155814, 54697.72651136878, 55042.0, 55060.72469637626, 55094.44905550893, 635384.0, 651115.0, 653236.7338163279, 653667.0, 654459.0, 42979.0, 43570.34644480272, 43758.9060273483, 44651.55348446474, 44769.552304476536, 47181.0, 47911.76044119757, 48180.27729584129, 48369.7907552314, 49696.0, 34361.82819085888, 38470.615293847826, 38815.80592097021, 39609.0, 40796.1840763189, 35925.82037089798, 38312.61687383202, 38669.61330386773, 39259.80370098131, 39576.60423395729, 38442.231155377296, 39241.82274531769, 39244.80377598094, 39409.605903940595, 39695.81673008012, 32748.0, 34525.65474345325, 34533.13667158341, 34697.826510867286, 34839.0, 19786.90106549458, 20299.492512687302, 22084.564503307232, 24376.659283739566, 25400.618990715524], "value": 21059.473425954435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.1296070879812, 389.28371795895555, 393.3863173449421, 397.1936967955052, 417.768138683031, 20153.798462015377, 20387.08258128384, 21446.463838404048, 21460.57078858422, 21483.46291342717, 19430.70853937192, 19710.507237319074, 20535.38393848183, 20539.973001349947, 20592.794072059274, 5101.464346243647, 5481.342238931314, 5494.313210848644, 5511.476409741068, 5528.226048353234, 5446.836594902139, 5487.478689524489, 6037.547183961198, 6166.506679465654, 6170.105334726462, 6107.4808641265545, 6128.295246046695, 6140.815775526741, 6144.2012538369945, 6156.846078848042, 5384.1923711443205, 5597.412271711472, 5598.216249725042, 5606.971965140148, 5845.503132233765, 5195.480451954812, 5412.56699464042, 5444.482774136491, 5445.863853403657, 5453.263809385722, 14059.226742529157, 14080.507182248675, 21309.36071917845, 26082.0, 26726.599101013308, 1915.2530513099764, 2101.53766171442, 2232.5423288225875, 2261.027758064032, 2325.4418939454577, 8446.282066024396, 9808.509574521317, 10152.746181345452, 10190.082892539707, 14927.328270227757, 1632.5347276026275, 1684.6045858680402, 1705.3519662528283, 1739.6781595404764, 1781.7950935642436, 126211.36894315561, 133398.33300833532, 133696.6630333694, 134302.0, 134312.0, 10971.780564388668, 11041.33751974884, 11110.333379997226, 11686.532538698357, 11990.400479975966, 15340.923295383674, 15404.306806193748, 15413.460528881435, 15429.922850385901, 15463.072215667096, 8490.320774338039, 8646.43798153118, 8683.826323473495, 8782.165694259034, 8787.769712240291, 6420.133282006963, 7204.0, 8389.0, 8436.98756149266, 8480.575971201415, 3728.5339332583426, 4651.3720647712125, 4663.323818046432, 4664.277037059262, 4730.290456431494, 1924.4322924737237, 1983.7123617075442, 1985.960280794376, 2001.1495114576246, 2009.7889721579024, 865.536937738307, 902.8600566912139, 942.8821397325335, 974.4202199691173, 1039.365986748083, 14476.782848257284, 19119.904400478186, 20737.0, 20859.8527081012, 20899.268525601532, 953.1612181280567, 961.6682244625594, 978.2369751593768, 991.5389343955095, 1021.667957913681, 10526.631567895087, 10973.50619222137, 11054.723631909106, 11056.668299951014, 11070.39112848801, 472850.0, 580215.0, 608218.0, 621776.0, 628555.0, 135088.0, 143806.28096859658, 151086.0, 154191.0, 157027.21486392722, 1009.8788145422596, 1033.4109557552124, 1050.6131905884313, 1060.3002018667642, 1060.7295139739317, 18657.440276791716, 18812.623747525246, 18911.432657020312, 18914.0, 18950.24199032023, 39268.60731392649, 51409.0, 55653.886922262136, 57649.71175144098, 58007.0, 42145.78927105345, 80785.0, 82985.58507207426, 85058.72411913703, 86528.0, 8153.3477321812925, 8298.958505207434, 8369.581520924052, 8377.916220837713, 8681.826363472568, 12156.513739450676, 12839.165454245443, 12909.54816581415, 13055.34723263852, 13349.265790381622, 11075.390853503135, 11280.928311810365, 11455.541778329107, 11601.709957251136, 11702.70743231426, 10914.56341746353, 11113.83329250078, 11295.774084518427, 11378.943105284421, 11754.647360579196, 10692.305000174963, 11571.537138514703, 11878.762424751629, 11956.521739130338, 11985.101117416403, 8234.547099909563, 8259.669613215405, 8337.08292087882, 8513.872291915503, 8523.48859068458, 10457.9477102614, 11186.32882027081, 11462.65612031607, 11499.482523286639, 11552.59565915189], "value": 9591.873842251027, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.2078996157143, 1597.5526852481305, 1625.2280166920712, 1889.2726300374352, 2006.021058727789, 7831.216878312166, 8677.34919881009, 8895.911040889589, 8906.421082629624, 9022.142896424846, 8627.870581941275, 8853.601587928542, 8856.91143088569, 8859.911400885989, 8867.113288671146, 2406.4104294447884, 2436.2812970173754, 2436.658867758515, 2438.85366877987, 2441.4628781668002, 2392.162743039931, 2422.479166979108, 2432.671589335435, 2438.317271164077, 2444.6577479152934, 2393.617021276596, 2410.048031027743, 2418.141559746291, 2434.5982912819363, 2438.4879175373194, 2002.6395248855201, 2370.8458950168183, 2395.125779090628, 2401.2436082633913, 2415.867127307997, 2290.404494831356, 2389.139909632531, 2392.7607239275935, 2393.748656391071, 2396.3769419950763, 7812.0, 8035.397345199075, 8863.867041994376, 8936.955315223448, 8943.195112439847, 5142.742862856842, 5310.8141215057285, 5328.360596728378, 5354.598405119593, 5363.115086010804, 3448.1724386147143, 3528.1708798432346, 3597.406427939413, 3619.7285203609836, 3637.7635453695425, 1038.2939601071268, 1053.425882893817, 1058.4919238765358, 1059.1950117910367, 1060.1465820014885, 11596.246243994114, 11809.23239989398, 11823.290602563788, 11838.408079595985, 11839.822402663967, 4714.622830173578, 4822.807087716452, 4945.802167913315, 4950.25746138075, 4982.676126051795, 10218.693439196835, 10221.0, 10225.74435639115, 10238.948805255926, 10255.897441025643, 5415.620906536584, 5434.538064264581, 5435.565154787608, 5445.945540544544, 5448.991936491761, 4779.163646361872, 4872.049950259665, 4959.578435832923, 4974.253861920741, 4977.0294792515115, 3835.7506762060375, 3853.190829925691, 3911.2959667259597, 3966.7619942803526, 3979.840806367713, 1275.3495717184303, 1282.3011458755002, 1289.7227096174367, 1300.473308310136, 1301.6290357248138, 804.6620419423856, 886.7206829848577, 888.8711933070979, 896.050186801992, 954.2938225712923, 5699.601027928005, 5708.4862362387585, 5796.62321949072, 5866.325372582122, 5984.593620499179, 448.8272015274078, 464.1992562829093, 470.5764811669525, 470.7363876229322, 475.2135216217166, 3292.6378098409155, 3929.410588411704, 3983.1237127831832, 4005.4792876925812, 4129.772862492592, 10637.042666160083, 10661.147108231324, 10865.619703310345, 10903.890961090447, 10920.781584368428, 10660.786784264272, 10881.673549793519, 10888.40113793749, 10894.291871028357, 10921.12630989519, 7801.492902961403, 8097.838043239102, 8187.222213889671, 8196.754097377208, 8233.259006689426, 6010.2186715726675, 6035.0, 6105.511559075175, 6156.107364432221, 6173.938260617337, 9753.707388778348, 10141.645042423776, 10260.794784104426, 10277.486125693835, 10289.3311934724, 9746.951265243628, 10179.083882450615, 10360.274780765274, 10360.326578772356, 10392.688219353431, 7507.737229196952, 7596.81007974805, 7627.771166865003, 7701.229877012478, 7709.0749110107145, 8230.876536852073, 8364.079951205242, 8370.832583348178, 8372.41393102477, 8373.748787536384, 7426.851462970602, 7557.811054723676, 7587.620618968919, 7703.614819258903, 7763.883541746765, 7244.855102898018, 7418.814529636802, 7483.139438964371, 7510.399168066432, 7582.431317651088, 7095.467839911924, 7206.387457066208, 7218.9893414923, 7219.314165154302, 7223.241559636373, 4101.384792281182, 4763.595094416874, 4889.731064791471, 4933.703977761345, 5164.8192313268855, 4073.654052763557, 4959.82640607577, 5058.822941197041, 5079.64442489037, 5251.36983561975], "value": 4543.851129198441, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3546198363599, 299.7422216893473, 309.9693519049162, 312.3440774373815, 313.6753460168726, 4310.374995625632, 4783.617310615151, 4991.6256280779, 5190.896182076361, 5230.581553475722, 4144.41978123063, 4433.977830110853, 4663.300504924255, 4940.925886111711, 5137.948620513794, 3037.3598256941227, 3936.389859571771, 3967.920641587181, 3975.3166612536493, 3980.7810570418615, 3383.424817780971, 3412.5326109772786, 3745.906352341186, 3880.825362858678, 3988.840446382141, 3446.775959562633, 3845.9807700961455, 3931.6461518463334, 3933.960660393388, 3967.900802479947, 3563.322968635977, 3653.634636536365, 3905.7656540607372, 3918.784466854322, 3934.862279820207, 3450.3099380123826, 3452.982735086334, 3455.9654403455893, 3464.2898205867987, 3481.234128491728, 3619.873304434332, 4259.744415335059, 4549.385832912581, 4889.853304400874, 4994.325766021577, 4416.293393057128, 4425.800838962255, 4431.955680443219, 4464.665150113722, 4466.70966387187, 4947.851564453073, 5011.599072074226, 5192.299039629639, 5392.892142157174, 5417.729113544307, 3742.719296052807, 3744.719146064056, 3753.418220175877, 3760.461688360373, 3782.7541209821547, 4732.810687572527, 4996.575291100261, 5347.518723314882, 5531.806386776524, 5587.329520457531, 3276.950845737316, 3648.945265821015, 3660.450932360114, 3668.669819716238, 3699.075231192202, 3558.697510711567, 3739.6821270191795, 3764.8494060237285, 3841.5966323536186, 3852.24881148179, 3882.7575175943425, 4243.299855523804, 4388.846390376321, 4422.955770442319, 4483.394741709827, 4443.022535042285, 4500.424851302018, 5105.361829771278, 5145.07388670043, 5346.144616861284, 1143.348291473852, 1163.3310846263264, 1212.078820096733, 1225.9570915017932, 1311.6392991927296, 792.1286584756837, 798.7044793426353, 807.3581502705364, 820.8016296207485, 820.9671613135407, 3626.7461277710836, 4015.3135683013206, 4232.476308528927, 4241.406203131564, 4253.8723838284905, 4048.291548978917, 4098.7950602470355, 4295.098029413795, 4390.538993405647, 4619.5611416915335, 3852.691784657277, 4073.653739432122, 4169.144730594917, 4207.242696314693, 4214.6091789709935, 2382.344855164809, 2449.252977841775, 2464.6056630938965, 2774.8612569371853, 2849.0, 5145.536901678867, 5268.6575372600655, 5393.541548968382, 5820.563457740686, 5833.620814647034, 5580.86047848807, 5810.389909059488, 5830.988309033377, 5849.64902105875, 5851.502622277069, 5075.720835354017, 5435.266239057677, 5791.942080579224, 5840.620359676607, 5871.853203669857, 4438.667099967451, 5167.870803229949, 5566.832995010156, 5581.553475721851, 5713.857153571194, 4622.491525932212, 4803.214135801157, 5070.163423035158, 5343.839684809462, 5559.24950131727, 5200.063988482109, 5505.9174112387545, 5546.864457184094, 5842.123681447817, 5873.324567674601, 3806.2958352704836, 3828.8659896903473, 3832.827522761538, 3839.8080095995647, 3841.519810023747, 4786.976065119652, 5289.783349829638, 5607.299087614048, 5648.971755141198, 5720.885582288414, 4457.2645513489915, 4550.453945526426, 4898.877528061685, 5178.766955487087, 5497.36780270274, 4924.753762311799, 5106.438291787826, 5203.505666961632, 5393.741102425104, 5541.307336582927, 5394.838154855361, 5397.8380648580605, 5405.351357837085, 5421.918671219856, 5432.538234250133, 4632.984651676532, 5505.752241149238, 5511.917321240105, 5524.315083899449, 5558.888822223614, 4359.367891655756, 4696.976515117402, 4798.352222450064, 4814.940713043125, 5432.886258317115], "value": 3832.9380470843034, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.4923985431582, 643.745720440426, 650.6356440393383, 676.577139287945, 741.0269365272784, 16819.41132060378, 17584.0, 17604.38384656537, 17672.64654706905, 17707.1146442678, 15272.541823745278, 16172.919135404307, 16223.918880405581, 16342.182890855469, 16419.671606567892, 742.5099434373315, 922.6355589542144, 927.9211267042276, 932.2309094996621, 932.724846170378, 891.8617614269799, 895.7984453497976, 896.5114012863005, 897.5198268926126, 900.4282280751737, 972.3193764364937, 974.1038244814788, 977.4623956823754, 980.6371642492288, 981.249344251648, 972.8054389122133, 973.0, 974.1914211204684, 974.6539978307669, 974.9171320437771, 711.8433944532195, 713.7894321175265, 713.8822094354425, 714.5212707485997, 716.0, 14880.925595372062, 15306.387744490206, 15407.0, 15490.30293636789, 15499.690006199926, 12135.514579416813, 12322.053211127888, 12414.131010829155, 12588.748225035539, 12804.359782010863, 10054.748631284276, 10237.283390162691, 10243.692689219335, 10473.214508911862, 10581.73545661357, 3432.742544309162, 3465.289615628815, 3467.1852114753015, 3499.667531584521, 3582.8387722552548, 55181.448185518035, 62912.0, 77332.22667773363, 85157.57421212917, 86694.0, 7895.763127106196, 9474.95262523683, 9528.19010384125, 9652.469114198646, 10063.282294931421, 20726.0, 20866.79133208679, 20896.582068358548, 20907.163713451595, 20927.79072209289, 7236.529625574321, 7327.633618319062, 7339.1926888043345, 7416.184219735825, 7482.289182527651, 3888.6694630956686, 3936.3111455495173, 3941.3693808990424, 3947.4670919426085, 3952.209558088394, 3565.108722819295, 3667.2298817248675, 3674.356987527172, 3677.7793332400142, 3680.9631903681156, 641.1120597971842, 660.3561527510639, 662.9900551491731, 680.829792551862, 681.2914568848342, 482.495791897468, 502.639601457356, 549.9312585926759, 552.9861753456196, 575.4791913318453, 7642.579658118857, 7933.0, 7995.040595128622, 8053.83892322162, 8068.3948703847445, 540.3542766394199, 556.4157634483776, 560.5095541401274, 560.7728869807735, 562.3420597900466, 3741.43878418234, 3762.454444105644, 3790.22300428417, 3828.8085595720104, 3852.210296889131, 90420.6436903447, 91396.0, 91812.54093729489, 92073.07926920646, 92928.0, 66194.66902665456, 71936.64031679912, 76266.44360131033, 77630.6118469404, 79889.203877864, 71135.0, 71146.7983940724, 71597.92603110957, 71934.2806571938, 72228.63885680538, 7512.849743005, 7558.2441755823975, 7669.501482403626, 7696.461247712607, 7903.68385264583, 44332.44836430709, 45484.0, 45751.542484574726, 47478.52521474741, 47534.28698569455, 56674.7166264166, 63140.73718525512, 63860.68069659622, 64028.35971640224, 64104.0, 9841.507924603598, 9935.801283974424, 10008.699739007841, 10012.949935250277, 10161.237907157141, 10569.207309451667, 10845.347313237184, 10959.506822192861, 10964.56141754321, 11768.823467648162, 10117.443540605338, 10440.0, 10582.788344232918, 10622.946885265525, 10669.199810014125, 9773.169280610944, 9965.451900145561, 10201.54093065799, 10560.0, 10621.097206737513, 8975.820483590422, 9290.442573445614, 10027.04743049409, 10269.178465722875, 10291.639792607222, 8765.386422950332, 8823.382363234512, 8859.778505537155, 8899.911000889908, 8903.73288801337, 7373.188949215475, 7889.960550197212, 8151.225633564802, 8231.876521852297, 8332.291755200877], "value": 6704.081965584138, "unit": "score"}], "name": "dromaeo_css", "value": 7147.5193991877795, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 111.0, 112.0, 112.0, 111.0, 112.0, 112.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 124.0, 127.0, 239.0, 146.0, 164.0, 139.0, 116.0, 169.0, 138.0], "value": 152.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 185.0, 184.0, 185.0, 184.0, 182.0, 184.0, 183.0, 180.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 56.0, 124.0, 56.0, 60.0, 58.0, 73.0, 134.0, 80.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 134.0, 78.0, 84.0, 77.0, 101.0, 81.0, 79.0, 100.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 84.0, 87.0, 83.0, 89.0, 84.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 86.0, 85.0, 85.0, 85.0, 86.0, 88.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 60.0, 54.0, 55.0, 55.0, 56.0, 118.0, 58.0, 56.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 71.0, 73.0, 70.0, 69.0, 67.0, 69.0, 68.0, 67.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 88.0, 97.0, 98.0, 86.0, 86.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 128.0, 137.0, 125.0, 172.0, 142.0, 141.0, 220.0, 137.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 163.0, 165.0, 162.0, 189.0, 220.0, 181.0, 189.0, 218.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 64.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1501.8, "alertThreshold": 2.0}]} 12:37:55 INFO - Return code: 0 12:37:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:37:55 ERROR - # TBPL SUCCESS # 12:37:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:37:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:37:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:37:55 INFO - 2016-05-16 12:37:55.263 screenresolution[1951:16044] starting screenresolution argv=screenresolution get 12:37:55 INFO - 2016-05-16 12:37:55.265 screenresolution[1951:16044] Display 0: 1600x1200x32@60 12:37:55 INFO - 2016-05-16 12:37:55.271 screenresolution[1952:16047] starting screenresolution argv=screenresolution list 12:37:55 INFO - Available Modes on Display 0 12:37:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:37:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:37:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:37:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:37:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:37:55 INFO - Intel Iris: 12:37:55 INFO - Chipset Model: Intel Iris 12:37:55 INFO - Type: GPU 12:37:55 INFO - Bus: Built-In 12:37:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:37:55 INFO - Vendor: Intel (0x8086) 12:37:55 INFO - Device ID: 0x0a2e 12:37:55 INFO - Revision ID: 0x0009 12:37:55 INFO - Displays: 12:37:55 INFO - Display: 12:37:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:37:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:37:55 INFO - Display Serial Number: 1600x1200 60 12:37:55 INFO - Main Display: Yes 12:37:55 INFO - Mirror: Off 12:37:55 INFO - Online: Yes 12:37:55 INFO - Rotation: Supported 12:37:55 INFO - Return code: 0 12:37:55 INFO - Running post-action listener: _resource_record_post_action 12:37:55 INFO - ##### 12:37:55 INFO - ##### Finished run-tests step (success) 12:37:55 INFO - ##### 12:37:55 INFO - Running post-run listener: _resource_record_post_run 12:37:55 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 831488; Write bytes: 692371968; Read time: 11; Write time: 2273 12:37:55 INFO - TinderboxPrint: CPU usage
53.6% 12:37:55 INFO - TinderboxPrint: I/O read bytes / time
831,488 / 11 12:37:55 INFO - TinderboxPrint: I/O write bytes / time
692,371,968 / 2,273 12:37:55 INFO - TinderboxPrint: CPU idle
2,250.7 (46.4%) 12:37:55 INFO - TinderboxPrint: CPU system
961.9 (19.8%) 12:37:55 INFO - TinderboxPrint: CPU user
1,637.0 (33.8%) 12:37:55 INFO - TinderboxPrint: Swap in / out
395,776,000 / 0 12:37:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 384453120; Write bytes: 378951168; Read time: 28525; Write time: 677 12:37:55 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 831488; Write bytes: 294009856; Read time: 11; Write time: 1551 12:37:55 INFO - Running post-run listener: _upload_blobber_files 12:37:55 INFO - Blob upload gear active. 12:37:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:37:55 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 12:37:55 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'] 12:37:55 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 12:37:56 INFO - (blobuploader) - INFO - Open directory for files ... 12:37:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:37:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:37:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:57 INFO - (blobuploader) - INFO - Done attempting. 12:37:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:37:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:37:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:58 INFO - (blobuploader) - INFO - Done attempting. 12:37:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:37:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:38:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:01 INFO - (blobuploader) - INFO - Done attempting. 12:38:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:38:01 INFO - Return code: 0 12:38:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:38:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:38:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aca8f9f89ab9b575876658a26a092c81823cfd72052230d4a80ae15e3e2fab60b0de3d4a15b82491ad2180f3fc62686dae1d292c9ddff359af2a10e8109557a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/004c14b008064edd9f9e916e69225ed901821ced6d2117aaf355da264dcc3f5168a02be9f6125b5ffe6160f6baabc34ae31713c5de4f4db63824843abf3272db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f420a32a9388c2e6a29be5a92b5404c5b4a78f912764a30f4b667f58893f4451475feb82dd35adb80453bc69d7fbd5352ce84a61fc6be15ea30b88bd42dd1b"} 12:38:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:38:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:38:01 INFO - Contents: 12:38:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aca8f9f89ab9b575876658a26a092c81823cfd72052230d4a80ae15e3e2fab60b0de3d4a15b82491ad2180f3fc62686dae1d292c9ddff359af2a10e8109557a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/004c14b008064edd9f9e916e69225ed901821ced6d2117aaf355da264dcc3f5168a02be9f6125b5ffe6160f6baabc34ae31713c5de4f4db63824843abf3272db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f420a32a9388c2e6a29be5a92b5404c5b4a78f912764a30f4b667f58893f4451475feb82dd35adb80453bc69d7fbd5352ce84a61fc6be15ea30b88bd42dd1b"} 12:38:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:38:01 INFO - Copying logs to upload dir... 12:38:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:38:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.000338 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-16 12:38:01.520510) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 12:38:01.524737) ========= 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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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/aca8f9f89ab9b575876658a26a092c81823cfd72052230d4a80ae15e3e2fab60b0de3d4a15b82491ad2180f3fc62686dae1d292c9ddff359af2a10e8109557a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/004c14b008064edd9f9e916e69225ed901821ced6d2117aaf355da264dcc3f5168a02be9f6125b5ffe6160f6baabc34ae31713c5de4f4db63824843abf3272db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f420a32a9388c2e6a29be5a92b5404c5b4a78f912764a30f4b667f58893f4451475feb82dd35adb80453bc69d7fbd5352ce84a61fc6be15ea30b88bd42dd1b"} build_url:https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010962 build_url: 'https://queue.taskcluster.net/v1/task/b-WmbG2fSM-OjwsePigp2w/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/aca8f9f89ab9b575876658a26a092c81823cfd72052230d4a80ae15e3e2fab60b0de3d4a15b82491ad2180f3fc62686dae1d292c9ddff359af2a10e8109557a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/004c14b008064edd9f9e916e69225ed901821ced6d2117aaf355da264dcc3f5168a02be9f6125b5ffe6160f6baabc34ae31713c5de4f4db63824843abf3272db", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c1f420a32a9388c2e6a29be5a92b5404c5b4a78f912764a30f4b667f58893f4451475feb82dd35adb80453bc69d7fbd5352ce84a61fc6be15ea30b88bd42dd1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 12:38:01.559398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:38:01.559731) ========= 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.QbCl5Ho5Tj/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.NYO1fgAJqD/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.005048 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 12:38:01.619131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 12:38:01.619583) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 12:38:01.620592) ========= ========= Total master_lag: 1.65 =========