builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0141 starttime: 1464101793.24 results: success (0) buildid: 20160524061714 builduid: f23c37c7b8614c89a3b8173d225331b8 revision: f074838712a0b061bde3b1cca3b68971609d7b92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.240481) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.240985) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.241340) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.698084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.698874) ========= 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.ejfWCztIro/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.ZkcBImMLxD/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 07:56:33-- 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% 15.4M=0.001s 2016-05-24 07:56:33 (15.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110749 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.831777) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.832211) ========= 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.ejfWCztIro/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.ZkcBImMLxD/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.028117 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:33.881600) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:56:33.881969) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f074838712a0b061bde3b1cca3b68971609d7b92 --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 f074838712a0b061bde3b1cca3b68971609d7b92 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ejfWCztIro/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.ZkcBImMLxD/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 07:56:33,956 truncating revision to first 12 chars 2016-05-24 07:56:33,956 Setting DEBUG logging. 2016-05-24 07:56:33,957 attempt 1/10 2016-05-24 07:56:33,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f074838712a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 07:56:35,610 unpacking tar archive at: fx-team-f074838712a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.901390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:56:35.803040) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:35.803365) ========= script_repo_revision: f074838712a0b061bde3b1cca3b68971609d7b92 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:35.803956) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:35.804397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:56:35.820049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-24 07:56:35.820404) ========= /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.ejfWCztIro/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.ZkcBImMLxD/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:56:35 INFO - MultiFileLogger online at 20160524 07:56:35 in /builds/slave/test 07:56:35 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 07:56:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:56:36 INFO - {'append_to_log': False, 07:56:36 INFO - 'base_work_dir': '/builds/slave/test', 07:56:36 INFO - 'blob_upload_branch': 'Fx-Team', 07:56:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:56:36 INFO - 'branch': 'Fx-Team', 07:56:36 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:36 INFO - 'config_files': ('talos/mac_config.py',), 07:56:36 INFO - 'default_actions': ('clobber', 07:56:36 INFO - 'read-buildbot-config', 07:56:36 INFO - 'download-and-extract', 07:56:36 INFO - 'populate-webroot', 07:56:36 INFO - 'create-virtualenv', 07:56:36 INFO - 'install', 07:56:36 INFO - 'run-tests'), 07:56:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:36 INFO - 'download_minidump_stackwalk': True, 07:56:36 INFO - 'download_symbols': 'ondemand', 07:56:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:56:36 INFO - 'tooltool.py': '/tools/tooltool.py', 07:56:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:56:36 INFO - '/tools/misc-python/virtualenv.py')}, 07:56:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:36 INFO - 'installer_path': 'installer.exe', 07:56:36 INFO - 'log_level': 'info', 07:56:36 INFO - 'log_name': 'talos', 07:56:36 INFO - 'log_to_console': True, 07:56:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:56:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:56:36 INFO - 'opt_config_files': (), 07:56:36 INFO - 'pip_index': False, 07:56:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:36 INFO - 'cmd': ('bash', 07:56:36 INFO - '-c', 07:56:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:36 INFO - 'enabled': True, 07:56:36 INFO - 'halt_on_failure': False, 07:56:36 INFO - 'name': 'check_screen_resolution'},), 07:56:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:56:36 INFO - 'cmd': ('bash', 07:56:36 INFO - '-c', 07:56:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:56:36 INFO - 'enabled': True, 07:56:36 INFO - 'halt_on_failure': False, 07:56:36 INFO - 'name': 'check_screen_resolution'},), 07:56:36 INFO - 'run_cmd_checks_enabled': True, 07:56:36 INFO - 'sps_profile': False, 07:56:36 INFO - 'sps_profile_interval': 0, 07:56:36 INFO - 'suite': 'dromaeojs', 07:56:36 INFO - 'system_bits': '32', 07:56:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:36 INFO - 'title': 't-yosemite-r7-0141', 07:56:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:56:36 INFO - 'use_talos_json': True, 07:56:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:56:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:36 INFO - 'work_dir': 'build'} 07:56:36 INFO - ##### 07:56:36 INFO - ##### Running clobber step. 07:56:36 INFO - ##### 07:56:36 INFO - Running pre-action listener: _resource_record_pre_action 07:56:36 INFO - Running main action method: clobber 07:56:36 INFO - rmtree: /builds/slave/test/build 07:56:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:56:37 INFO - Running post-action listener: _resource_record_post_action 07:56:37 INFO - ##### 07:56:37 INFO - ##### Finished clobber step (success) 07:56:37 INFO - ##### 07:56:37 INFO - ##### 07:56:37 INFO - ##### Running read-buildbot-config step. 07:56:37 INFO - ##### 07:56:37 INFO - Running pre-action listener: _resource_record_pre_action 07:56:37 INFO - Running main action method: read_buildbot_config 07:56:37 INFO - Using buildbot properties: 07:56:37 INFO - { 07:56:37 INFO - "project": "", 07:56:37 INFO - "product": "firefox", 07:56:37 INFO - "script_repo_revision": "production", 07:56:37 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:56:37 INFO - "repository": "", 07:56:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:56:37 INFO - "buildid": "20160524061714", 07:56:37 INFO - "pgo_build": "False", 07:56:37 INFO - "basedir": "/builds/slave/test", 07:56:37 INFO - "buildnumber": 27, 07:56:37 INFO - "slavename": "t-yosemite-r7-0141", 07:56:37 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:56:37 INFO - "platform": "yosemite_r7", 07:56:37 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:56:37 INFO - "branch": "fx-team", 07:56:37 INFO - "repo_path": "integration/fx-team", 07:56:37 INFO - "revision": "f074838712a0b061bde3b1cca3b68971609d7b92", 07:56:37 INFO - "stage_platform": "macosx64", 07:56:37 INFO - "builduid": "f23c37c7b8614c89a3b8173d225331b8", 07:56:37 INFO - "slavebuilddir": "test" 07:56:37 INFO - } 07:56:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:56:37 INFO - Running post-action listener: _resource_record_post_action 07:56:37 INFO - ##### 07:56:37 INFO - ##### Finished read-buildbot-config step (success) 07:56:37 INFO - ##### 07:56:37 INFO - ##### 07:56:37 INFO - ##### Running download-and-extract step. 07:56:37 INFO - ##### 07:56:37 INFO - Running pre-action listener: _resource_record_pre_action 07:56:37 INFO - Running main action method: download_and_extract 07:56:37 INFO - mkdir: /builds/slave/test/build/tests 07:56:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:37 INFO - https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:56:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:56:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:56:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:39 INFO - Downloaded 1199 bytes. 07:56:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:56:39 INFO - Using the following test package requirements: 07:56:39 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:56:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:56:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:56:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'jsshell-mac.zip'], 07:56:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:56:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:56:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:56:39 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:56:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:56:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:56:39 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:56:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:39 INFO - https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:56:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:56:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:56:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:40 INFO - Downloaded 18632886 bytes. 07:56:40 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'] 07:56:40 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 07:56:44 INFO - Return code: 0 07:56:44 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 07:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:44 INFO - https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:56:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:56:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:56:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:45 INFO - Downloaded 18632886 bytes. 07:56:45 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'] 07:56:45 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 07:56:48 INFO - Return code: 0 07:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:48 INFO - https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:56:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:56:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:56:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:49 INFO - Downloaded 10840359 bytes. 07:56:49 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'] 07:56:49 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 07:56:50 INFO - Return code: 0 07:56:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:56:50 INFO - https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:56:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:56:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:56:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:56:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPNBcSkqSVycT0fJZSPvXA/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 07:56:54 INFO - Downloaded 110242521 bytes. 07:56:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:56:54 INFO - mkdir: /builds/slave/test/properties 07:56:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:56:54 INFO - Writing to file /builds/slave/test/properties/build_url 07:56:54 INFO - Contents: 07:56:54 INFO - build_url:https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:56:55 INFO - Running post-action listener: _resource_record_post_action 07:56:55 INFO - Running post-action listener: set_extra_try_arguments 07:56:55 INFO - ##### 07:56:55 INFO - ##### Finished download-and-extract step (success) 07:56:55 INFO - ##### 07:56:55 INFO - ##### 07:56:55 INFO - ##### Running populate-webroot step. 07:56:55 INFO - ##### 07:56:55 INFO - Running pre-action listener: _resource_record_pre_action 07:56:55 INFO - Running main action method: populate_webroot 07:56:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:56:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:56:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:56:55 INFO - '--tppagecycles', 07:56:55 INFO - '7'], 07:56:55 INFO - 'tests': ['tsvgm']}}, 07:56:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:56:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:56:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:56:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:56:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:56:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['damp', 'tps']}, 07:56:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'tests': ['damp', 'tps']}, 07:56:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['dromaeo_dom']}, 07:56:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:56:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['a11yr', 07:56:55 INFO - 'ts_paint', 07:56:55 INFO - 'tpaint', 07:56:55 INFO - 'sessionrestore', 07:56:55 INFO - 'sessionrestore_no_auto_restore', 07:56:55 INFO - 'tabpaint']}, 07:56:55 INFO - 'other-e10s': {'tests': ['a11yr', 07:56:55 INFO - 'ts_paint', 07:56:55 INFO - 'tpaint', 07:56:55 INFO - 'sessionrestore', 07:56:55 INFO - 'sessionrestore_no_auto_restore', 07:56:55 INFO - 'tabpaint']}, 07:56:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:56:55 INFO - 'ts_paint', 07:56:55 INFO - 'tpaint', 07:56:55 INFO - 'sessionrestore', 07:56:55 INFO - 'sessionrestore_no_auto_restore', 07:56:55 INFO - 'tabpaint']}, 07:56:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:56:55 INFO - 'ts_paint', 07:56:55 INFO - 'tpaint', 07:56:55 INFO - 'sessionrestore', 07:56:55 INFO - 'sessionrestore_no_auto_restore', 07:56:55 INFO - 'tabpaint']}, 07:56:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['a11yr', 07:56:55 INFO - 'ts_paint', 07:56:55 INFO - 'tpaint', 07:56:55 INFO - 'sessionrestore', 07:56:55 INFO - 'sessionrestore_no_auto_restore', 07:56:55 INFO - 'tabpaint']}, 07:56:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['a11yr', 07:56:55 INFO - 'ts_paint', 07:56:55 INFO - 'tpaint', 07:56:55 INFO - 'sessionrestore', 07:56:55 INFO - 'sessionrestore_no_auto_restore', 07:56:55 INFO - 'tabpaint']}, 07:56:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['tsvgx', 07:56:55 INFO - 'tsvgr_opacity', 07:56:55 INFO - 'tart', 07:56:55 INFO - 'tscrollx', 07:56:55 INFO - 'cart']}, 07:56:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:56:55 INFO - 'tsvgr_opacity', 07:56:55 INFO - 'tart', 07:56:55 INFO - 'tscrollx', 07:56:55 INFO - 'cart']}, 07:56:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'talos_options': ['--disable-e10s'], 07:56:55 INFO - 'tests': ['tp5o']}, 07:56:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'tests': ['tp5o']}, 07:56:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'talos_options': ['--disable-e10s', 07:56:55 INFO - '--xperf_path', 07:56:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:55 INFO - 'tests': ['tp5n']}, 07:56:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:56:55 INFO - 'talos_options': ['--xperf_path', 07:56:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:56:55 INFO - 'tests': ['tp5n']}}, 07:56:55 INFO - 'talos.zip': {'path': '', 07:56:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:56:55 INFO - Running post-action listener: _resource_record_post_action 07:56:55 INFO - ##### 07:56:55 INFO - ##### Finished populate-webroot step (success) 07:56:55 INFO - ##### 07:56:55 INFO - ##### 07:56:55 INFO - ##### Running create-virtualenv step. 07:56:55 INFO - ##### 07:56:55 INFO - Running pre-action listener: _resource_record_pre_action 07:56:55 INFO - Running main action method: create_virtualenv 07:56:55 INFO - Creating virtualenv /builds/slave/test/build/venv 07:56:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:56:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:56:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:56:55 INFO - Using real prefix '/tools/python27' 07:56:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:56:56 INFO - Installing distribute.............................................................................................................................................................................................done. 07:56:59 INFO - Installing pip.................done. 07:56:59 INFO - Return code: 0 07:56:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:56: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')]} 07:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:59 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:59 INFO - 'HOME': '/Users/cltbld', 07:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:59 INFO - 'LOGNAME': 'cltbld', 07:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:59 INFO - 'MOZ_NO_REMOTE': '1', 07:56:59 INFO - 'NO_EM_RESTART': '1', 07:56:59 INFO - 'PAGER': '/bin/cat', 07:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:59 INFO - 'PWD': '/builds/slave/test', 07:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:59 INFO - 'SHELL': '/bin/bash', 07:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:56:59 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:59 INFO - 'USER': 'cltbld', 07:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:59 INFO - 'XPC_FLAGS': '0x0', 07:56:59 INFO - 'XPC_SERVICE_NAME': '0', 07:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:56:59 INFO - Cleaning up... 07:56:59 INFO - Return code: 0 07:56:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:56: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')]} 07:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56: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')]} 07:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:56:59 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:56:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:56:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:56:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:56:59 INFO - 'HOME': '/Users/cltbld', 07:56:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:56:59 INFO - 'LOGNAME': 'cltbld', 07:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:56:59 INFO - 'MOZ_NO_REMOTE': '1', 07:56:59 INFO - 'NO_EM_RESTART': '1', 07:56:59 INFO - 'PAGER': '/bin/cat', 07:56:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:56:59 INFO - 'PWD': '/builds/slave/test', 07:56:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:56:59 INFO - 'SHELL': '/bin/bash', 07:56:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:56:59 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:56:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:56:59 INFO - 'USER': 'cltbld', 07:56:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:56:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:56:59 INFO - 'XPC_FLAGS': '0x0', 07:56:59 INFO - 'XPC_SERVICE_NAME': '0', 07:56:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:56:59 INFO - Downloading/unpacking psutil>=3.1.1 07:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:56:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:56:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:57:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:02 INFO - Installing collected packages: psutil 07:57:02 INFO - Running setup.py install for psutil 07:57:03 INFO - building 'psutil._psutil_osx' extension 07:57:03 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 07:57:03 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 07:57:03 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 07:57:03 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 07:57:03 INFO - building 'psutil._psutil_posix' extension 07:57:03 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 07:57:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:57:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:57:03 INFO - ^ 07:57:03 INFO - 1 warning generated. 07:57:03 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 07:57:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:04 INFO - Successfully installed psutil 07:57:04 INFO - Cleaning up... 07:57:04 INFO - Return code: 0 07:57:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:04 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:04 INFO - 'HOME': '/Users/cltbld', 07:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:04 INFO - 'LOGNAME': 'cltbld', 07:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:04 INFO - 'MOZ_NO_REMOTE': '1', 07:57:04 INFO - 'NO_EM_RESTART': '1', 07:57:04 INFO - 'PAGER': '/bin/cat', 07:57:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:04 INFO - 'PWD': '/builds/slave/test', 07:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:04 INFO - 'SHELL': '/bin/bash', 07:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:04 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:04 INFO - 'USER': 'cltbld', 07:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:04 INFO - 'XPC_FLAGS': '0x0', 07:57:04 INFO - 'XPC_SERVICE_NAME': '0', 07:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:57:06 INFO - Installing collected packages: mozsystemmonitor 07:57:06 INFO - Running setup.py install for mozsystemmonitor 07:57:07 INFO - Successfully installed mozsystemmonitor 07:57:07 INFO - Cleaning up... 07:57:07 INFO - Return code: 0 07:57:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:07 INFO - 'HOME': '/Users/cltbld', 07:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:07 INFO - 'LOGNAME': 'cltbld', 07:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:07 INFO - 'MOZ_NO_REMOTE': '1', 07:57:07 INFO - 'NO_EM_RESTART': '1', 07:57:07 INFO - 'PAGER': '/bin/cat', 07:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:07 INFO - 'PWD': '/builds/slave/test', 07:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:07 INFO - 'SHELL': '/bin/bash', 07:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:07 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:07 INFO - 'USER': 'cltbld', 07:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:07 INFO - 'XPC_FLAGS': '0x0', 07:57:07 INFO - 'XPC_SERVICE_NAME': '0', 07:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:07 INFO - Downloading/unpacking blobuploader==1.2.4 07:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:09 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:10 INFO - Downloading docopt-0.6.1.tar.gz 07:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:11 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:11 INFO - Running setup.py install for blobuploader 07:57:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:11 INFO - Running setup.py install for requests 07:57:11 INFO - Running setup.py install for docopt 07:57:11 INFO - Successfully installed blobuploader requests docopt 07:57:11 INFO - Cleaning up... 07:57:11 INFO - Return code: 0 07:57:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:11 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')]} 07:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:11 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')]} 07:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:11 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:11 INFO - 'HOME': '/Users/cltbld', 07:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:11 INFO - 'LOGNAME': 'cltbld', 07:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:11 INFO - 'MOZ_NO_REMOTE': '1', 07:57:11 INFO - 'NO_EM_RESTART': '1', 07:57:11 INFO - 'PAGER': '/bin/cat', 07:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:11 INFO - 'PWD': '/builds/slave/test', 07:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:11 INFO - 'SHELL': '/bin/bash', 07:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:11 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:11 INFO - 'USER': 'cltbld', 07:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:11 INFO - 'XPC_FLAGS': '0x0', 07:57:11 INFO - 'XPC_SERVICE_NAME': '0', 07:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-JsmsYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ux1FCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-OjuyF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-e2A1ZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-zDedrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-t4m11u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ePNAFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-jakOq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-W674Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-iAj1XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-eDkXbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-BFfhZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-JQePPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-or3wgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bRQXat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-zD5eDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Dbu6yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:14 INFO - Running setup.py install for manifestparser 07:57:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:14 INFO - Running setup.py install for mozcrash 07:57:14 INFO - Running setup.py install for mozdebug 07:57:14 INFO - Running setup.py install for mozdevice 07:57:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:57:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:14 INFO - Running setup.py install for mozfile 07:57:14 INFO - Running setup.py install for mozhttpd 07:57:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:14 INFO - Running setup.py install for mozinfo 07:57:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozInstall 07:57:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozleak 07:57:15 INFO - Running setup.py install for mozlog 07:57:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for moznetwork 07:57:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozprocess 07:57:15 INFO - Running setup.py install for mozprofile 07:57:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:15 INFO - Running setup.py install for mozrunner 07:57:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Running setup.py install for mozscreenshot 07:57:16 INFO - Running setup.py install for moztest 07:57:16 INFO - Running setup.py install for mozversion 07:57:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:16 INFO - Cleaning up... 07:57:16 INFO - Return code: 0 07:57:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57: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')]} 07:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57: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')]} 07:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:16 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:16 INFO - 'HOME': '/Users/cltbld', 07:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:16 INFO - 'LOGNAME': 'cltbld', 07:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:16 INFO - 'MOZ_NO_REMOTE': '1', 07:57:16 INFO - 'NO_EM_RESTART': '1', 07:57:16 INFO - 'PAGER': '/bin/cat', 07:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:16 INFO - 'PWD': '/builds/slave/test', 07:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:16 INFO - 'SHELL': '/bin/bash', 07:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:16 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:16 INFO - 'USER': 'cltbld', 07:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:16 INFO - 'XPC_FLAGS': '0x0', 07:57:16 INFO - 'XPC_SERVICE_NAME': '0', 07:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Fpm95t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-z98VHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-tCGADJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-UBEY9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-PDaSu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-mH8JhG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-0PFhvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Y8TkOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bsHCdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Qhk3Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:17 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)) 07:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-pjWRYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-8BUoCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-uSEXsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-2Ok3oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bhmuRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-TtoAGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:18 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)) 07:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:18 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-TCHz6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:57:18 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)) 07:57:18 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)) 07:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:57:18 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)) 07:57:18 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)) 07:57:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:21 INFO - Downloading blessings-1.6.tar.gz 07:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:57:21 INFO - Installing collected packages: blessings 07:57:21 INFO - Running setup.py install for blessings 07:57:21 INFO - Successfully installed blessings 07:57:21 INFO - Cleaning up... 07:57:21 INFO - Return code: 0 07:57:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:57:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:21 INFO - Reading from file tmpfile_stdout 07:57:21 INFO - Current package versions: 07:57:21 INFO - blessings == 1.6 07:57:21 INFO - blobuploader == 1.2.4 07:57:21 INFO - docopt == 0.6.1 07:57:21 INFO - manifestparser == 1.1 07:57:21 INFO - mozInstall == 1.12 07:57:21 INFO - mozcrash == 0.17 07:57:21 INFO - mozdebug == 0.1 07:57:21 INFO - mozdevice == 0.48 07:57:21 INFO - mozfile == 1.2 07:57:21 INFO - mozhttpd == 0.7 07:57:21 INFO - mozinfo == 0.9 07:57:21 INFO - mozleak == 0.1 07:57:21 INFO - mozlog == 3.2 07:57:21 INFO - moznetwork == 0.27 07:57:21 INFO - mozprocess == 0.22 07:57:21 INFO - mozprofile == 0.28 07:57:21 INFO - mozrunner == 6.11 07:57:21 INFO - mozscreenshot == 0.1 07:57:21 INFO - mozsystemmonitor == 0.3 07:57:21 INFO - moztest == 0.7 07:57:21 INFO - mozversion == 1.4 07:57:21 INFO - psutil == 3.1.1 07:57:21 INFO - requests == 1.2.3 07:57:21 INFO - wsgiref == 0.1.2 07:57:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:21 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:21 INFO - 'HOME': '/Users/cltbld', 07:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:21 INFO - 'LOGNAME': 'cltbld', 07:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:21 INFO - 'MOZ_NO_REMOTE': '1', 07:57:21 INFO - 'NO_EM_RESTART': '1', 07:57:21 INFO - 'PAGER': '/bin/cat', 07:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:21 INFO - 'PWD': '/builds/slave/test', 07:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:21 INFO - 'SHELL': '/bin/bash', 07:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:21 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:21 INFO - 'USER': 'cltbld', 07:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:21 INFO - 'XPC_FLAGS': '0x0', 07:57:21 INFO - 'XPC_SERVICE_NAME': '0', 07:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:57:22 INFO - Cleaning up... 07:57:22 INFO - Return code: 0 07:57:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:57:22 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')]} 07:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:22 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')]} 07:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:22 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 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:57:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:22 INFO - 'HOME': '/Users/cltbld', 07:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:22 INFO - 'LOGNAME': 'cltbld', 07:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:22 INFO - 'MOZ_NO_REMOTE': '1', 07:57:22 INFO - 'NO_EM_RESTART': '1', 07:57:22 INFO - 'PAGER': '/bin/cat', 07:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:22 INFO - 'PWD': '/builds/slave/test', 07:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:22 INFO - 'SHELL': '/bin/bash', 07:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:22 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:22 INFO - 'USER': 'cltbld', 07:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:22 INFO - 'XPC_FLAGS': '0x0', 07:57:22 INFO - 'XPC_SERVICE_NAME': '0', 07:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:22 INFO - Downloading/unpacking jsonschema 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57: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 07:57:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:57:24 INFO - Downloading/unpacking functools32 (from jsonschema) 07:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:25 INFO - Downloading functools32-3.2.3-2.tar.gz 07:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:57:25 INFO - warning: no files found matching '*.txt' 07:57:25 INFO - no previously-included directories found matching 'build' 07:57:25 INFO - no previously-included directories found matching 'dist' 07:57:25 INFO - no previously-included directories found matching '.git*' 07:57:25 INFO - Installing collected packages: jsonschema, functools32 07:57:25 INFO - Running setup.py install for functools32 07:57:25 INFO - warning: no files found matching '*.txt' 07:57:25 INFO - no previously-included directories found matching 'build' 07:57:25 INFO - no previously-included directories found matching 'dist' 07:57:25 INFO - no previously-included directories found matching '.git*' 07:57:25 INFO - Successfully installed jsonschema functools32 07:57:25 INFO - Cleaning up... 07:57:25 INFO - Return code: 0 07:57:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101440a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101446570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bcbec30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, '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.ejfWCztIro/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.ZkcBImMLxD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 07:57:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:57:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:25 INFO - 'HOME': '/Users/cltbld', 07:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:25 INFO - 'LOGNAME': 'cltbld', 07:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:25 INFO - 'MOZ_NO_REMOTE': '1', 07:57:25 INFO - 'NO_EM_RESTART': '1', 07:57:25 INFO - 'PAGER': '/bin/cat', 07:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:25 INFO - 'PWD': '/builds/slave/test', 07:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:25 INFO - 'SHELL': '/bin/bash', 07:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:57:25 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:25 INFO - 'USER': 'cltbld', 07:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:25 INFO - 'XPC_FLAGS': '0x0', 07:57:25 INFO - 'XPC_SERVICE_NAME': '0', 07:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:25 INFO - Downloading/unpacking flake8 07:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:57:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:57:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:57:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:57:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:57:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:57:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:57:29 INFO - no previously-included directories found matching 'docs/_build' 07:57:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:57:29 INFO - Running setup.py install for pep8 07:57:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:57:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:57:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:57:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:57:29 INFO - no previously-included directories found matching 'docs/_build' 07:57:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:57:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:57:29 INFO - Cleaning up... 07:57:29 INFO - Return code: 0 07:57:29 INFO - Running post-action listener: _resource_record_post_action 07:57:29 INFO - Running post-action listener: _start_resource_monitoring 07:57:29 INFO - Starting resource monitoring. 07:57:29 INFO - ##### 07:57:29 INFO - ##### Finished create-virtualenv step (success) 07:57:29 INFO - ##### 07:57:29 INFO - ##### 07:57:29 INFO - ##### Running install step. 07:57:29 INFO - ##### 07:57:29 INFO - Running pre-action listener: _resource_record_pre_action 07:57:29 INFO - Running main action method: install 07:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:57:30 INFO - Reading from file tmpfile_stdout 07:57:30 INFO - Detecting whether we're running mozinstall >=1.0... 07:57:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:57:30 INFO - Reading from file tmpfile_stdout 07:57:30 INFO - Output received: 07:57:30 INFO - Usage: mozinstall [options] installer 07:57:30 INFO - Options: 07:57:30 INFO - -h, --help show this help message and exit 07:57:30 INFO - -d DEST, --destination=DEST 07:57:30 INFO - Directory to install application into. [default: 07:57:30 INFO - "/builds/slave/test"] 07:57:30 INFO - --app=APP Application being installed. [default: firefox] 07:57:30 INFO - mkdir: /builds/slave/test/build/application 07:57:30 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'] 07:57:30 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 07:58:03 INFO - Reading from file tmpfile_stdout 07:58:03 INFO - Output received: 07:58:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:58:03 INFO - Running post-action listener: _resource_record_post_action 07:58:03 INFO - ##### 07:58:03 INFO - ##### Finished install step (success) 07:58:03 INFO - ##### 07:58:03 INFO - ##### 07:58:03 INFO - ##### Running run-tests step. 07:58:03 INFO - ##### 07:58:03 INFO - Running pre-action listener: _resource_record_pre_action 07:58:03 INFO - Running main action method: run_tests 07:58:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:58:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:58:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:58:03 INFO - 2016-05-24 07:58:03.963 screenresolution[1644:7544] starting screenresolution argv=screenresolution get 07:58:03 INFO - 2016-05-24 07:58:03.966 screenresolution[1644:7544] Display 0: 1600x1200x32@60 07:58:03 INFO - 2016-05-24 07:58:03.972 screenresolution[1645:7546] starting screenresolution argv=screenresolution list 07:58:03 INFO - Available Modes on Display 0 07:58:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:58:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:58:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:58:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:58:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:58:04 INFO - Intel Iris: 07:58:04 INFO - Chipset Model: Intel Iris 07:58:04 INFO - Type: GPU 07:58:04 INFO - Bus: Built-In 07:58:04 INFO - VRAM (Dynamic, Max): 1536 MB 07:58:04 INFO - Vendor: Intel (0x8086) 07:58:04 INFO - Device ID: 0x0a2e 07:58:04 INFO - Revision ID: 0x0009 07:58:04 INFO - Displays: 07:58:04 INFO - Display: 07:58:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:58:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:58:04 INFO - Display Serial Number: 1600x1200 60 07:58:04 INFO - Main Display: Yes 07:58:04 INFO - Mirror: Off 07:58:04 INFO - Online: Yes 07:58:04 INFO - Rotation: Supported 07:58:04 INFO - Return code: 0 07:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:58:04 INFO - Python 2.7.3 07:58:04 INFO - Return code: 0 07:58:04 INFO - grabbing minidump binary from tooltool 07:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 07:58:04 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 0x1012e4d30>, '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 0x101108db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012dbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:58:04 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 07:58:04 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 07:58:04 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 07:58:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:58:04 INFO - Return code: 0 07:58:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:58:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:58:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:58:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:58:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:58:05 INFO - Return code: 0 07:58:05 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/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:58:05 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/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ejfWCztIro/Render', 07:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:05 INFO - 'HOME': '/Users/cltbld', 07:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:05 INFO - 'LOGNAME': 'cltbld', 07:58:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:58:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:05 INFO - 'MOZ_NO_REMOTE': '1', 07:58:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:58:05 INFO - 'NO_EM_RESTART': '1', 07:58:05 INFO - 'PAGER': '/bin/cat', 07:58:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:05 INFO - 'PWD': '/builds/slave/test', 07:58:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:05 INFO - 'SHELL': '/bin/bash', 07:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZkcBImMLxD/Listeners', 07:58:05 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 07:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:05 INFO - 'USER': 'cltbld', 07:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:05 INFO - 'XPC_FLAGS': '0x0', 07:58:05 INFO - 'XPC_SERVICE_NAME': '0', 07:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:05 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/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:58:05 INFO - mozversion application_buildid: 20160524061714 07:58:05 INFO - mozversion application_changeset: f074838712a0b061bde3b1cca3b68971609d7b92 07:58:05 INFO - mozversion application_display_name: Nightly 07:58:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:58:05 INFO - mozversion application_name: Firefox 07:58:05 INFO - mozversion application_remotingname: firefox 07:58:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:58:05 INFO - mozversion application_vendor: Mozilla 07:58:05 INFO - mozversion application_version: 49.0a1 07:58:05 INFO - mozversion platform_buildid: 20160524061714 07:58:05 INFO - mozversion platform_changeset: f074838712a0b061bde3b1cca3b68971609d7b92 07:58:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:58:05 INFO - mozversion platform_version: 49.0a1 07:58:05 INFO - using testdate: 1464101885 07:58:05 INFO - actual date: 1464101885 07:58:05 INFO - starting webserver on 'localhost:49275' 07:58:05 INFO - SUITE-START | Running 2 tests 07:58:05 INFO - TEST-START | dromaeo_css 07:58:05 INFO - operating with platform_type : mac_ 07:58:05 INFO - Initialising browser for dromaeo_css test... 07:58:05 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpfUrq7w/profile http://localhost:49275/getInfo.html) 07:58:07 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 07:58:07 INFO - PROCESS | 1663 | colorDepth:24 07:58:07 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 07:58:07 INFO - PROCESS | 1663 | __metrics 07:58:08 INFO - PROCESS | 1663 | 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" 07:58:08 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:58:08 INFO - TEST-INFO | 1663: exit 0 07:58:08 INFO - Browser initialized. 07:58:08 INFO - Running cycle 1/1 for dromaeo_css test... 07:58:08 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpfUrq7w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:58:20 INFO - PROCESS | 1665 | RSS: Main: 239235072 07:58:20 INFO - PROCESS | 1665 | 07:59:12 INFO - PROCESS | 1665 | 1464101952137 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] 08:01:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 08:01:24 INFO - PROCESS | 1665 | RSS: Main: 401494016 08:01:24 INFO - PROCESS | 1665 | 08:04:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 08:04:27 INFO - PROCESS | 1665 | RSS: Main: 377749504 08:04:27 INFO - PROCESS | 1665 | 08:07:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 08:07:31 INFO - PROCESS | 1665 | RSS: Main: 372031488 08:07:31 INFO - PROCESS | 1665 | 08:10:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 08:10:33 INFO - PROCESS | 1665 | RSS: Main: 370065408 08:10:34 INFO - PROCESS | 1665 | 08:13:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 08:13:37 INFO - PROCESS | 1665 | RSS: Main: 339746816 08:13:37 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 08:16:41 INFO - PROCESS | 1665 | RSS: Main: 379359232 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | __start_tp_report 08:16:41 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 08:16:41 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 08:16:41 INFO - PROCESS | 1665 | |i|pagename|runs| 08:16:41 INFO - PROCESS | 1665 | |0;dojo.html;3450.2926899985505;3459.7232221422287;3486.755927085105;3489.7382696297777;3527.082958430807;5583.5254003409755;5592.692401917903;5664.660120392779;5681.744321505532;5714.342850572186;5664.745086471108;5678.971605141979;5720.170575266584;5806.158107074471;5819.970900145504;5165.535101840834;5176.55999240065;5254.92117618236;5255.395629502599;5342.90470453558;5304.655197412175;5305.708186049766;5338.546223571001;5361.383440904307;5396;5138.177891537355;5191.117510023305;5213.957208558285;5238.842834714965;5249.658772179834;5188.610854185951;5195.636305458619;5229.320188375525;5230.947690523084;5234.555062819665;5211.609129315279;5228.55557277628;5257.211418287249;5264.394594621629;5299.920501192485;5525.11598144299;5585.888282234373;5649.0396632572565;5686.658800471985;5758.942410575883;6207.34822843604;6259.655718935457;6270.843228919268;6274.372562743689;6277.780277690259;5503.834884953458;6322.304546499881;6328.905066424008;6358.682065896733;6360.7137678804565;5701.0308247598;5748.166515855261;5770.278715160647;5813.79651712188;5816.30573628305;6323.652199129;6330.113784070234;6331.081993110973;6332.366763323631;6349.2698339690605;6190.690465476709;6203.410675985774;6209.534284928558;6225.159603453476;6228.28374736902;6141.578063794151;6194.132821405007;6265.530085243625;6279.686015699197;6304.401081897213;6189.50483961282;6237.812865614039;6238.251409830758;6240.126382306481;6261.843453913689;6217.285012223652;6255.218097737783;6276.089966954766;6291.276503202099;6305.306416294204;4887.535684110004;4934.358533390637;4944.554990050912;4975.825846095369;4975.850724478271;4448.110377924428;4482.036362182146;4483.30508771141;4483.686141970096;4497.527759585262;5629.802956896489;5644.520215781622;5645.407232240637;5666.206731057655;5721.5136713379825;6267.091271765568;6273.912762262976;6302.275238347649;6312.274088479883;6316.684165791784;5683.346415162309;5685.118806584986;5695.40198279183;5698.515626171739;5741.598088133791;5266.420693723688;5323.095073837457;5352.491513306308;5356.758945847446;5417.349918009785;6295.999999999908;6354.872902542016;6369.522285828489;6380.617162970237;6386.712597933197;6268.467180289544;6317.684115794284;6333.429991300805;6338.556301058881;6348;6249.187605611241;6256.937430625818;6299.937000629935;6383.840403989938;6630.171228596426;5154.433012368561;6198.144226620478;6220.564560480723;6235.220597425322;6237.875242495215;5228.790848366023;6149.446549810538;6178.41305076017;6223.253209614875;6279.215098112736;6066.969665151646;6091.02543593023;6103.542234332354;6120.265568131853;6121.173641558333;6077.24034495688;6096.859313929741;6115.021596544531;6117.143599895929;6132.601380910227;5883.94116058834;5903.557233207441;5967.343592204941;5967.8209653710455;6050.878982420415;6153.077038444269;6157.384261573807;6160;6171;6190.690465476799;5845.240118784531;6001.159837622649;6008.279006519246;6021;6057.18228039209 08:16:41 INFO - PROCESS | 1665 | |1;ext.html;471.8136336147222;497.16972655665035;498.3969397029594;500.05240070067225;503.50153348185285;30529;43906.78046609766;44294.7785261074;44903.326450103275;46904.76547617257;39613.8019309903;45710;46012.3098153528;46285.305720414224;46306.30540541886;8255.669773209065;9119.270458363315;9709.126178643939;9870.851937220947;9925.057119573665;8764;11800.881991180107;12066.457009434553;12084.818727719092;12242.81635775464;11857.7628447431;12055.939720301387;12141.93929030358;12216.511339546407;12238.632841014783;8763.430377025474;11993;12056.63830085099;12220.022398208122;12263.93868030663;11794.410279485992;11865.169438139334;12005.159638825286;12077.758444831054;12129.696757581043;42043.73868783942;42583.787081064394;42994.57005429937;43038.78480607608;43200;3139.780215384924;3241;3373.8481768320485;3417.6924076832956;3586.4261718125235;29115.272118197216;30359.392812143633;31386.05841824749;31396.686033139606;31536.369272614647;2714.7556719895106;2727.7954153438573;2823.661160660734;2836.5461526155923;2871.296532349567;509156;510575;512972;513453.9999999963;513495;64584.677076614316;64681;65278.673606631666;66861.99707004399;67089.6582068356;68280.6585967077;70492;70546;70555.29444705487;70685.75885446889;16896.915515422344;17060.658786824442;17258.050807205724;17486.863353881956;17513.7372939406;11849.34828584419;12213.938930305469;12516.74966500665;13049.934750326378;13236.13965092266;18018.549536261435;18589.44231673052;18657.813421865878;18666.719999200024;18747.718784218247;6149.354317796657;6469.288378278386;6850.725970961105;6852.965735171292;6983.127109111361;952.8808898887639;956.4739393333639;988.5847943863595;1047.0628787235535;1054.7995880782628;28743.56884646732;28913.132606021853;29281.853590732335;29328;29361.265968350534;1083.3554035348955;1088.6407485529915;1131.6491887514896;1150.045462266317;1162.2096974057722;14638.487652932095;14953.327100280296;14976.326065327086;15009.474668386554;15417.922910385376;538991;545117;545467;545527;545605;59953;60162.699186503785;60197;60212;60222;1155.3356819828632;1310.0633047371175;1432.534426311432;1446.7034257977089;1469.889758268134;29696.851515742284;30137.849310753307;30292.24269393283;30383.848080759453;30492.894342832486;53872.73063634657;54271;54960;55008.449915500336;55059.62350941259;528518.2166802426;546630;547144.264278692;547758;547936;35454;40945.59054409418;41090.38364424596;41314.79342603268;42687.57312426836;46287;46996.82507937192;48503;48591.757041214565;48700.025999480524;28563.714362856106;32683;39188.41217381799;40296;40686.593134069466;38914.6108538911;38965;39101.41347879727;39627.60372396239;40126.79619611416;32420;38418;39007.024824379616;39015.414768777926;39403.2119357617;33392.66607333896;33881.49177762286;34532.65467345295;34732.305353892276;35104;21086.367408977756;23796.762032379454;25001.374965625277;25684.688378045317;25950.09174678877 08:16:41 INFO - PROCESS | 1665 | |2;jquery.html;317.81090251300475;369.3868178823153;386.99226015479707;395.70519962627844;407.8225971702308;16875.831241687578;21451.57096858064;21782.237621683245;21881.249500039998;22082.779172208295;20864.47838804031;20893;21788.782112178873;21863.016164272605;21897;5776.104703770911;5823.155642431845;5854.853628659296;5999.070144127667;6002.669853158074;4149.315362965122;4365.890852728676;5855.385184555625;5914.378990206031;6218.31598524162;6489.448396886247;6490.993895946136;6496.187976502937;6505.479561635081;6516.67416629169;6192.256929168506;6520.673966301666;6528.93471065288;6547.214334279869;6552.279249282551;3703.2963736890174;5313.601479889024;5640.238567793336;5692.686902220376;5694.601377903548;24916.3770905727;25915.352116197246;26247.868760656074;26500.86749566259;26621.600675989877;1942.524081600003;2089.4880754215164;2092.5187206942337;2341.8594884306826;2430.9756902431104;9551.426914385089;9889.406635601887;10125.848112278321;10295.176385889112;11693.415329233589;1642.30202164081;1685.4353791479869;1739.6172841974744;1807.493901707524;1879.1731638079202;103170;120283.39858300652;121927;122468;126069.36965315207;9237.584308706124;11401.600943966921;11728.530858765724;11740.58907938218;11901.761964760657;14652.853471465362;14799;14822.481213157704;14835.629109272355;14864.777028344584;7441;8587.914120858712;8604.182602652738;8785.912140878509;8920.509371984474;7091.397231235403;8747.956260218785;8752.124787521325;8775.91224087751;8908.10918908104;4475.865724028285;4583.541645835457;4585.679002469795;4620.237660786;4661.254199328091;1558.3221298735086;1727.9827201727821;1778.9525982451864;1801.5586181385381;1859.5258209156848;842.6755699055908;868.9435186712969;883.7127933421535;946.914777670013;972.6936015155203;19963.60072798536;20816.687749683766;20845;20939.243491789784;21117.155313787276;903.5978989349808;957.9473128977974;977.9315447918577;980.6224603527693;1001.7095042437758;10771.084457821173;10943.507542160622;11031.889681103246;11158.665240042812;11181.94409027966;599403;604072;604765;613531;613630;110005;130182.34908825395;135329;141282.2935885314;143316;966.6954909203579;992.3648864726581;996.7209181429066;1019.0573719309609;1038.325088692356;17798;18269.17788699485;19223.94268315256;19289.131989060807;19343;50306;50659.49340506548;51144;53245;53736.462635373144;79895;80471.37878795351;81283.59358203171;81454;81720.18279817364;6591.472682185509;7672.693092276247;7937.960310198412;7971.681132754625;8234.135415781497;11671;12270.4478298475;12410.441530130985;12593.496260149857;12672.556460523838;10360.015798499131;10411.271211015157;10542.57829686804;10599.258051936291;10626.203034772578;8188.344932405274;10378.844317335397;10473.162147028068;10637.14902807758;10754.784904302027;7600.581967991813;10595.523201455799;10656.360618362924;10937.781244375226;11192.832107518556;7951.721689741063;7973.481723687942;7982.6806927724565;8145.755627330953;8193.46742461738;7705.383569314329;9315.208207302456;10452.895471045193;10610.204234682275;10729.088027517748 08:16:41 INFO - PROCESS | 1665 | |3;mootools.html;1300.8048792681102;1361.5302720561406;1521.6043828604559;1555.0125670199423;1974.6643070677983;7910.4462687611895;8724.607392667338;8922.330825188104;8938.508382038994;9105.317101217403;8655.783605409852;8914.598843052063;8944.910550894489;8949.955250223757;8971.102889711026;2112.9788702113015;2186.6719992001167;2337.100216416679;2355.9057637694473;2367.597508423572;2298.0463107810237;2340.789328960393;2342.0631747301045;2344.109238489372;2344.531093781242;2007.9397618071407;2343.8242131840093;2360.6459031145378;2369.9289021329387;2377.3462297868136;2099.5066159452517;2308.653701944705;2370.502194539149;2374.085976898889;2378.9762102379013;1956.4717526267827;2393.3059412770276;2399.748026457214;2400.59189937711;2401.4116541447283;8808.911910880874;8835.469871807647;8878.822423551557;8883.244924181452;8889.911100888972;4151.543330233671;5422.864428389322;5441.673499590037;5446.537044351235;5451.863703407407;3384.7969121852766;3436.759426840122;3616;3621.3119507293795;3629.0927268182954;1015.4821041268932;1018.1854516386935;1035.6064695415769;1038.7195457226583;1059.1526778577183;12133.907948284785;12143.817842732366;12148.574799881962;12162.452689628988;12164;4714.422307647266;4806.567408933143;4839.637027222902;4866.367372241586;4886.902261954741;10009.599616015277;10123.493825308706;10124;10132.088112069949;10138.594456221816;5386.757595908115;5399.676019438846;5417.133258678671;5437.265969094122;5442.047641662693;4420.867373978722;4588.105319462739;4801.423829140525;4943.258511223274;4944.752762361867;3938.3895496198134;4006.7996600169877;4042.656374208166;4098.282800509899;4121.690873184523;1093.4259513755183;1281.1608396500308;1292.7802273613509;1296;1310.60026691858;783.7068836419838;895;896.8834051573253;937.4422218779815;959.0744931141481;5669.433056694297;5805.36141024487;5886.293644762656;5954.702264886739;6009.06859436788;454.51140024473824;456.93831332770316;459.80688110993464;971.7133445633414;1020.3061917895902;3897.279003384383;3953.367461206251;3967.7222594418113;4030.9596904031173;4146.688998325138;8980.236679882153;10750.78498430027;10975;10977.615783447542;10992.395418252074;10841;10877.184211184034;10943.890561094287;10986.725331866768;10986.780264394827;8091.884624419641;8175.754727358189;8299.543525106177;8485.32117430604;8500.914990850013;5952.5833191676165;6014.1580178775885;6015.218021657157;6108.847278818065;6140.416660417253;10151.64469243573;10234.437105958947;10250.58997640086;10282.434466104436;10296.794064118525;9731.805363892825;10198.643047493302;10219;10379.740506487398;10450.216233782648;5713.542916566747;6117.3270940197435;7160.749373771893;7346.96326518364;7659.463837531543;8372.204640559137;8419.494830310201;8526.914730852612;8530.317574594143;8538.975322961285;7398.778036658909;7538.547687138789;7638.6020611786;7665.69337226527;7673.693052278069;7321.670524826289;7388.815279618053;7403.888941665987;7527.473076884566;7564.092308922744;6954.200266969161;6985.615791131537;7019.824504387431;7022.087128673445;7031.261717519586;4026.8389264429093;4858.198397264553;4908.042931628298;4968;5119.974400128124;4846.61227101824;4914.778834952364;4955.702657840541;4965.8013679452415;5183.041137389595 08:16:41 INFO - PROCESS | 1665 | |4;prototype.html;261.26844834463503;304.4337532190126;312.2505985634477;316.93344397676475;319.94081094997415;4282.593153650406;4718.363020992174;5401.594880383977;5414.511009472392;5460.808871689492;4417.293233082707;4683.976580117103;5360.597955153369;5384.811531596395;5398.784048638059;3921.568627450978;3974.5627980922077;4054.6958978076614;4087.3255912774357;4129.380592911058;4096.914317705796;4102.282100632392;4132.752034877902;4148.502179738435;4166.333386658135;3425.201769071233;3484.9128771780656;3512.701420379271;3600.567931848169;4125.154343359604;3368.79787212768;3842.6934842153664;3971.7886044756337;4050.230456213325;4112.239235741397;3140.026591756563;3256.127726557239;3446.9486806524;3551.5738111426535;3571.8928432147077;4387.561243875619;5060.21566657165;5133.5102475299445;5149.0474262261605;5242.266082748418;3897.8830635080994;4390;4671.159191345557;4683.742394168319;4700.647451441156;5571.60998730085;5635.830925072254;5647.632903861236;5687.601867869251;5701.28961311609;3860;3864.265789499986;3867.45855580219;3877.631624995649;3878.45701601776;5617.578681598897;5949.494292985145;5952.470200164913;5969.194158788551;5996.460318571306;3291.720203782705;3477.1828620274214;3549.290141971616;3834.2523207974177;3891.5135608048995;3519.856046784805;3938.9212215755524;3941.9802900985887;3990.940135897964;3992.86024989124;3766.7551609145316;4359.476862776423;4631.9536804631525;4632.536746325408;4679.602233810097;5743.712814359265;5772.682502462321;5785.566082543826;5808.854778630483;5828.388019258002;1332.9466821327233;1361.2649169448387;1366.2349084512705;1367.412012834471;1372.6980064386019;788.3850596534712;815.1888870573817;824.0070714788666;832.24681663095;838.6268110690684;4603.363342959092;5158;5300.867478313073;5325.6272060956135;5329.147336426153;5210.635255532076;5393.514583687487;5862.355140934494;5897.351291358033;5912.940870591325;4566.246569316092;4683.859484215479;4912.38595175603;5202.947970520247;5318.654287471302;3051.7711171662213;3068.8185048755945;3078.9384212316077;3090.969090309113;3114;5812.534997200299;5993.220881285405;6030.48740857032;6081.5065823012355;6100.054491553817;5667.838093190886;5889;5990.760369585255;5991.221141251609;6009.699515024231;5107.489251074863;5364.95383400241;5382.219578161144;5993.460588547052;6032.607880487754;5493.23094766741;5774.48029677331;5807.651540907559;5856.180134781219;5922.407759224043;5733.483986441241;5741.368449470639;5767.307923049261;5834.445727655866;5834.94165058344;6018.759249629966;6028;6067.24159480065;6082.391760823883;6098.32918378969;3428.0802750458824;3866.284737323604;3937.488126543531;3944.3886197639413;3964.4449777030663;5820.417958204146;5851.765929362778;5852.151438041546;5855.151003104441;5882.882342353043;5166.922496162636;5659.745311461076;5707.172459993195;5709.172170035238;5713.94292055971;4819.445763737215;5068.619853510928;5320.973395133;5328.547073498796;5773.942260577341;5229.712365819916;5575.6933368663495;5623.971880140573;5628.971855140698;5631.690257035739;4293.0769884474985;4916.655834091579;5456.945430545644;5564.4713752193475;5623.2408624835125;4675.462321832897;5510.669359838423;5589.189567512769;5599.412061733622;5608.355039170385 08:16:41 INFO - PROCESS | 1665 | |5;yui.html;647.818610788979;657.7171816119073;697.4246246846353;717.2182321269817;750.6847124207834;14476.710465790691;15248.085114893114;15264.389424423038;15280.770788438182;15352.078875267493;14840.851591484083;15287;15353.232338383064;15573.454929077485;15589.844101558981;724.5725022236879;907.9727608171748;910.2717825739383;916.8303863785188;917.0966597901062;726.5967388099602;887.5961437545933;913.7806926337697;918.6922381002339;923.7829110159107;943.1605870775015;957.2055194188841;960.5581432541039;963.1379914976092;965.0976337124811;741.7774667599698;857.5412154497315;918.8437965545874;959.2278216036088;960.4237457525463;706.6713978000204;707.1337611426013;708.0582824842946;708.6775517139689;708.8405108850519;13639.931800341034;13724.862751372459;13795.379207935666;13821.585352439342;13832.654183645389;12079.637610871689;12103.33431661267;12190;12260.712625174407;12465.626031219077;9374.812503749887;10906.454677266185;11025.44872756359;11039.83440248397;11476.139289553317;3937.153511994935;3952.170044290702;3954.703397245218;3982.38273067675;4094.897627559305;43179.13641727148;43450.34824477604;48039;91784.54107729418;93061.08980736646;9194.678186263449;9319.627214911328;9525.714228573153;9549;9637.566309516089;20847.166113355295;20881.895590522254;20914.954252287323;20917.26789562358;20942.162313507288;7251.3836323912;7355.264473552602;7449.180590135081;7460.0301960744755;7530.209328020588;3958.2281455115976;4023.2154729827403;4034.253663072341;4054.2904991626347;4130.359794231899;2967.554866770002;3414.7097496712563;3525.506429099928;3526.5062891195253;3541.1678255609913;618.6504624886925;627.6077451592755;636.9904451433232;644.0178727440597;645.7998020613625;482.3054801086424;530.7770736290768;552.1855263486326;577.7457918515839;581.0209796492923;7753.922460775433;7765.7670269892;7836;7942.920570794334;7955.84088318242;474.2341119092641;544.4881811097556;563.4647085269011;572.8682403047239;594.040624391613;3470.6529347065093;3594.658507441789;3753.0992561784974;3760.7743535387963;3818.446325282874;92612.53693731489;94687;94822.57766133375;95283.52358238165;95952.52023739976;71786.282137179;76404;77342.61328693433;81022.59488702519;83717.16282837215;74078.62960685162;74291.25708742843;74570.25429745742;75265;75498;7187.460940429384;7195;7352.96323518379;7386.224446433047;7394.371478424394;46633.76683116562;46664.53335466602;48125.984251968504;48311.27533087077;49415.25877111774;54820;64975.67512162598;66121.33878661152;66400.6679966597;66485;9648.565814538502;9664.661736839458;9665.855012174963;9841.360311579725;9991.850122248317;10413.854704247657;10675.412852292888;10732.980366865135;10832.837507437238;11031.282966607145;9772.85340719904;9965.750856228653;10080.949595251977;10101.747456313651;10251.84622230682;8788.560571971248;9356.719298421058;9975;10145.847812282675;10394.532246049097;8625.913740862761;9560.521973901416;9759.853602196115;9802.411855288705;10117.29178957466;8199.508029518249;8268.917310826815;8662.950976867056;8701.695440659547;8798.20816126526;8278.45813592396;8466.74599762008;8505.319574434154;8560.91439085601;8581.184787445183 08:16:41 INFO - PROCESS | 1665 | __end_tp_report 08:16:41 INFO - PROCESS | 1665 | __start_cc_report 08:16:41 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1667 08:16:41 INFO - PROCESS | 1665 | __end_cc_report 08:16:41 INFO - PROCESS | 1665 | __startTimestamp1464103001285__endTimestamp 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | ------- Summary: start ------- 08:16:41 INFO - PROCESS | 1665 | Number of tests: 6 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5759.20 Median:5967.82 stddev:632.85 (10.6%) stddev-sans-first:608.31 08:16:41 INFO - PROCESS | 1665 | Values: 3450.3 3459.7 3486.8 3489.7 3527.1 5583.5 5592.7 5664.7 5681.7 5714.3 5664.7 5679.0 5720.2 5806.2 5820.0 5165.5 5176.6 5254.9 5255.4 5342.9 5304.7 5305.7 5338.5 5361.4 5396.0 5138.2 5191.1 5214.0 5238.8 5249.7 5188.6 5195.6 5229.3 5230.9 5234.6 5211.6 5228.6 5257.2 5264.4 5299.9 5525.1 5585.9 5649.0 5686.7 5758.9 6207.3 6259.7 6270.8 6274.4 6277.8 5503.8 6322.3 6328.9 6358.7 6360.7 5701.0 5748.2 5770.3 5813.8 5816.3 6323.7 6330.1 6331.1 6332.4 6349.3 6190.7 6203.4 6209.5 6225.2 6228.3 6141.6 6194.1 6265.5 6279.7 6304.4 6189.5 6237.8 6238.3 6240.1 6261.8 6217.3 6255.2 6276.1 6291.3 6305.3 4887.5 4934.4 4944.6 4975.8 4975.9 4448.1 4482.0 4483.3 4483.7 4497.5 5629.8 5644.5 5645.4 5666.2 5721.5 6267.1 6273.9 6302.3 6312.3 6316.7 5683.3 5685.1 5695.4 5698.5 5741.6 5266.4 5323.1 5352.5 5356.8 5417.3 6296.0 6354.9 6369.5 6380.6 6386.7 6268.5 6317.7 6333.4 6338.6 6348.0 6249.2 6256.9 6299.9 6383.8 6630.2 5154.4 6198.1 6220.6 6235.2 6237.9 5228.8 6149.4 6178.4 6223.3 6279.2 6067.0 6091.0 6103.5 6120.3 6121.2 6077.2 6096.9 6115.0 6117.1 6132.6 5883.9 5903.6 5967.3 5967.8 6050.9 6153.1 6157.4 6160.0 6171.0 6190.7 5845.2 6001.2 6008.3 6021.0 6057.2 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:68513.55 Median:38666.31 stddev:141882.03 (366.9%) stddev-sans-first:142188.07 08:16:41 INFO - PROCESS | 1665 | Values: 471.8 497.2 498.4 500.1 503.5 30529.0 43906.8 44294.8 44903.3 46904.8 39613.8 45710.0 46012.3 46285.3 46306.3 8255.7 9119.3 9709.1 9870.9 9925.1 8764.0 11800.9 12066.5 12084.8 12242.8 11857.8 12055.9 12141.9 12216.5 12238.6 8763.4 11993.0 12056.6 12220.0 12263.9 11794.4 11865.2 12005.2 12077.8 12129.7 42043.7 42583.8 42994.6 43038.8 43200.0 3139.8 3241.0 3373.8 3417.7 3586.4 29115.3 30359.4 31386.1 31396.7 31536.4 2714.8 2727.8 2823.7 2836.5 2871.3 509156.0 510575.0 512972.0 513454.0 513495.0 64584.7 64681.0 65278.7 66862.0 67089.7 68280.7 70492.0 70546.0 70555.3 70685.8 16896.9 17060.7 17258.1 17486.9 17513.7 11849.3 12213.9 12516.7 13049.9 13236.1 18018.5 18589.4 18657.8 18666.7 18747.7 6149.4 6469.3 6850.7 6853.0 6983.1 952.9 956.5 988.6 1047.1 1054.8 28743.6 28913.1 29281.9 29328.0 29361.3 1083.4 1088.6 1131.6 1150.0 1162.2 14638.5 14953.3 14976.3 15009.5 15417.9 538991.0 545117.0 545467.0 545527.0 545605.0 59953.0 60162.7 60197.0 60212.0 60222.0 1155.3 1310.1 1432.5 1446.7 1469.9 29696.9 30137.8 30292.2 30383.8 30492.9 53872.7 54271.0 54960.0 55008.4 55059.6 528518.2 546630.0 547144.3 547758.0 547936.0 35454.0 40945.6 41090.4 41314.8 42687.6 46287.0 46996.8 48503.0 48591.8 48700.0 28563.7 32683.0 39188.4 40296.0 40686.6 38914.6 38965.0 39101.4 39627.6 40126.8 32420.0 38418.0 39007.0 39015.4 39403.2 33392.7 33881.5 34532.7 34732.3 35104.0 21086.4 23796.8 25001.4 25684.7 25950.1 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:35395.52 Median:13469.71 stddev:101448.21 (753.2%) stddev-sans-first:101698.60 08:16:41 INFO - PROCESS | 1665 | Values: 317.8 369.4 387.0 395.7 407.8 16875.8 21451.6 21782.2 21881.2 22082.8 20864.5 20893.0 21788.8 21863.0 21897.0 5776.1 5823.2 5854.9 5999.1 6002.7 4149.3 4365.9 5855.4 5914.4 6218.3 6489.4 6491.0 6496.2 6505.5 6516.7 6192.3 6520.7 6528.9 6547.2 6552.3 3703.3 5313.6 5640.2 5692.7 5694.6 24916.4 25915.4 26247.9 26500.9 26621.6 1942.5 2089.5 2092.5 2341.9 2431.0 9551.4 9889.4 10125.8 10295.2 11693.4 1642.3 1685.4 1739.6 1807.5 1879.2 103170.0 120283.4 121927.0 122468.0 126069.4 9237.6 11401.6 11728.5 11740.6 11901.8 14652.9 14799.0 14822.5 14835.6 14864.8 7441.0 8587.9 8604.2 8785.9 8920.5 7091.4 8748.0 8752.1 8775.9 8908.1 4475.9 4583.5 4585.7 4620.2 4661.3 1558.3 1728.0 1779.0 1801.6 1859.5 842.7 868.9 883.7 946.9 972.7 19963.6 20816.7 20845.0 20939.2 21117.2 903.6 957.9 977.9 980.6 1001.7 10771.1 10943.5 11031.9 11158.7 11181.9 599403.0 604072.0 604765.0 613531.0 613630.0 110005.0 130182.3 135329.0 141282.3 143316.0 966.7 992.4 996.7 1019.1 1038.3 17798.0 18269.2 19223.9 19289.1 19343.0 50306.0 50659.5 51144.0 53245.0 53736.5 79895.0 80471.4 81283.6 81454.0 81720.2 6591.5 7672.7 7938.0 7971.7 8234.1 11671.0 12270.4 12410.4 12593.5 12672.6 10360.0 10411.3 10542.6 10599.3 10626.2 8188.3 10378.8 10473.2 10637.1 10754.8 7600.6 10595.5 10656.4 10937.8 11192.8 7951.7 7973.5 7982.7 8145.8 8193.5 7705.4 9315.2 10452.9 10610.2 10729.1 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5737.57 Median:2587.11 stddev:3286.99 (127.1%) stddev-sans-first:3279.29 08:16:41 INFO - PROCESS | 1665 | Values: 1300.8 1361.5 1521.6 1555.0 1974.7 7910.4 8724.6 8922.3 8938.5 9105.3 8655.8 8914.6 8944.9 8950.0 8971.1 2113.0 2186.7 2337.1 2355.9 2367.6 2298.0 2340.8 2342.1 2344.1 2344.5 2007.9 2343.8 2360.6 2369.9 2377.3 2099.5 2308.7 2370.5 2374.1 2379.0 1956.5 2393.3 2399.7 2400.6 2401.4 8808.9 8835.5 8878.8 8883.2 8889.9 4151.5 5422.9 5441.7 5446.5 5451.9 3384.8 3436.8 3616.0 3621.3 3629.1 1015.5 1018.2 1035.6 1038.7 1059.2 12133.9 12143.8 12148.6 12162.5 12164.0 4714.4 4806.6 4839.6 4866.4 4886.9 10009.6 10123.5 10124.0 10132.1 10138.6 5386.8 5399.7 5417.1 5437.3 5442.0 4420.9 4588.1 4801.4 4943.3 4944.8 3938.4 4006.8 4042.7 4098.3 4121.7 1093.4 1281.2 1292.8 1296.0 1310.6 783.7 895.0 896.9 937.4 959.1 5669.4 5805.4 5886.3 5954.7 6009.1 454.5 456.9 459.8 971.7 1020.3 3897.3 3953.4 3967.7 4031.0 4146.7 8980.2 10750.8 10975.0 10977.6 10992.4 10841.0 10877.2 10943.9 10986.7 10986.8 8091.9 8175.8 8299.5 8485.3 8500.9 5952.6 6014.2 6015.2 6108.8 6140.4 10151.6 10234.4 10250.6 10282.4 10296.8 9731.8 10198.6 10219.0 10379.7 10450.2 5713.5 6117.3 7160.7 7347.0 7659.5 8372.2 8419.5 8526.9 8530.3 8539.0 7398.8 7538.5 7638.6 7665.7 7673.7 7321.7 7388.8 7403.9 7527.5 7564.1 6954.2 6985.6 7019.8 7022.1 7031.3 4026.8 4858.2 4908.0 4968.0 5120.0 4846.6 4914.8 4955.7 4965.8 5183.0 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4576.85 Median:5206.79 stddev:1441.25 (27.7%) stddev-sans-first:1408.43 08:16:41 INFO - PROCESS | 1665 | Values: 261.3 304.4 312.3 316.9 319.9 4282.6 4718.4 5401.6 5414.5 5460.8 4417.3 4684.0 5360.6 5384.8 5398.8 3921.6 3974.6 4054.7 4087.3 4129.4 4096.9 4102.3 4132.8 4148.5 4166.3 3425.2 3484.9 3512.7 3600.6 4125.2 3368.8 3842.7 3971.8 4050.2 4112.2 3140.0 3256.1 3446.9 3551.6 3571.9 4387.6 5060.2 5133.5 5149.0 5242.3 3897.9 4390.0 4671.2 4683.7 4700.6 5571.6 5635.8 5647.6 5687.6 5701.3 3860.0 3864.3 3867.5 3877.6 3878.5 5617.6 5949.5 5952.5 5969.2 5996.5 3291.7 3477.2 3549.3 3834.3 3891.5 3519.9 3938.9 3942.0 3990.9 3992.9 3766.8 4359.5 4632.0 4632.5 4679.6 5743.7 5772.7 5785.6 5808.9 5828.4 1332.9 1361.3 1366.2 1367.4 1372.7 788.4 815.2 824.0 832.2 838.6 4603.4 5158.0 5300.9 5325.6 5329.1 5210.6 5393.5 5862.4 5897.4 5912.9 4566.2 4683.9 4912.4 5202.9 5318.7 3051.8 3068.8 3078.9 3091.0 3114.0 5812.5 5993.2 6030.5 6081.5 6100.1 5667.8 5889.0 5990.8 5991.2 6009.7 5107.5 5365.0 5382.2 5993.5 6032.6 5493.2 5774.5 5807.7 5856.2 5922.4 5733.5 5741.4 5767.3 5834.4 5834.9 6018.8 6028.0 6067.2 6082.4 6098.3 3428.1 3866.3 3937.5 3944.4 3964.4 5820.4 5851.8 5852.2 5855.2 5882.9 5166.9 5659.7 5707.2 5709.2 5713.9 4819.4 5068.6 5321.0 5328.5 5773.9 5229.7 5575.7 5624.0 5629.0 5631.7 4293.1 4916.7 5456.9 5564.5 5623.2 4675.5 5510.7 5589.2 5599.4 5608.4 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17548.33 Median:702.05 stddev:25290.36 (3602.4%) stddev-sans-first:25329.47 08:16:41 INFO - PROCESS | 1665 | Values: 647.8 657.7 697.4 717.2 750.7 14476.7 15248.1 15264.4 15280.8 15352.1 14840.9 15287.0 15353.2 15573.5 15589.8 724.6 908.0 910.3 916.8 917.1 726.6 887.6 913.8 918.7 923.8 943.2 957.2 960.6 963.1 965.1 741.8 857.5 918.8 959.2 960.4 706.7 707.1 708.1 708.7 708.8 13639.9 13724.9 13795.4 13821.6 13832.7 12079.6 12103.3 12190.0 12260.7 12465.6 9374.8 10906.5 11025.4 11039.8 11476.1 3937.2 3952.2 3954.7 3982.4 4094.9 43179.1 43450.3 48039.0 91784.5 93061.1 9194.7 9319.6 9525.7 9549.0 9637.6 20847.2 20881.9 20915.0 20917.3 20942.2 7251.4 7355.3 7449.2 7460.0 7530.2 3958.2 4023.2 4034.3 4054.3 4130.4 2967.6 3414.7 3525.5 3526.5 3541.2 618.7 627.6 637.0 644.0 645.8 482.3 530.8 552.2 577.7 581.0 7753.9 7765.8 7836.0 7942.9 7955.8 474.2 544.5 563.5 572.9 594.0 3470.7 3594.7 3753.1 3760.8 3818.4 92612.5 94687.0 94822.6 95283.5 95952.5 71786.3 76404.0 77342.6 81022.6 83717.2 74078.6 74291.3 74570.3 75265.0 75498.0 7187.5 7195.0 7353.0 7386.2 7394.4 46633.8 46664.5 48126.0 48311.3 49415.3 54820.0 64975.7 66121.3 66400.7 66485.0 9648.6 9664.7 9665.9 9841.4 9991.9 10413.9 10675.4 10733.0 10832.8 11031.3 9772.9 9965.8 10080.9 10101.7 10251.8 8788.6 9356.7 9975.0 10145.8 10394.5 8625.9 9560.5 9759.9 9802.4 10117.3 8199.5 8268.9 8663.0 8701.7 8798.2 8278.5 8466.7 8505.3 8560.9 8581.2 08:16:41 INFO - PROCESS | 1665 | -------- Summary: end -------- 08:16:41 INFO - PROCESS | 1665 | 08:16:41 INFO - TEST-INFO | 1665: exit 0 08:16:41 INFO - TEST-OK | dromaeo_css | took 1116224ms 08:16:41 INFO - TEST-START | kraken 08:16:41 INFO - operating with platform_type : mac_ 08:16:41 INFO - Initialising browser for kraken test... 08:16:41 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpPSoei7/profile http://localhost:49275/getInfo.html) 08:16:44 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 08:16:44 INFO - PROCESS | 1675 | colorDepth:24 08:16:44 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 08:16:44 INFO - PROCESS | 1675 | __metrics 08:16:44 INFO - PROCESS | 1675 | 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" 08:16:44 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:16:44 INFO - TEST-INFO | 1675: exit 0 08:16:44 INFO - Browser initialized. 08:16:44 INFO - Running cycle 1/1 for kraken test... 08:16:44 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpPSoei7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:16:57 INFO - PROCESS | 1677 | RSS: Main: 238956544 08:16:57 INFO - PROCESS | 1677 | 08:16:59 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 08:17:00 INFO - PROCESS | 1677 | RSS: Main: 286326784 08:17:00 INFO - PROCESS | 1677 | 08:17:03 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 08:17:04 INFO - PROCESS | 1677 | RSS: Main: 295153664 08:17:04 INFO - PROCESS | 1677 | 08:17:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 08:17:08 INFO - PROCESS | 1677 | RSS: Main: 386363392 08:17:08 INFO - PROCESS | 1677 | 08:17:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 08:17:10 INFO - PROCESS | 1677 | RSS: Main: 284602368 08:17:10 INFO - PROCESS | 1677 | 08:17:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:17:13 INFO - PROCESS | 1677 | RSS: Main: 288817152 08:17:13 INFO - PROCESS | 1677 | 08:17:16 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 08:17:16 INFO - PROCESS | 1677 | RSS: Main: 325881856 08:17:16 INFO - PROCESS | 1677 | 08:17:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 08:17:20 INFO - PROCESS | 1677 | RSS: Main: 327356416 08:17:20 INFO - PROCESS | 1677 | 08:17:23 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 08:17:23 INFO - PROCESS | 1677 | RSS: Main: 327421952 08:17:23 INFO - PROCESS | 1677 | 08:17:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:17:26 INFO - PROCESS | 1677 | RSS: Main: 312803328 08:17:26 INFO - PROCESS | 1677 | 08:17:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:17:28 INFO - PROCESS | 1677 | RSS: Main: 320045056 08:17:28 INFO - PROCESS | 1677 | 08:17:30 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:17:31 INFO - PROCESS | 1677 | RSS: Main: 321400832 08:17:31 INFO - PROCESS | 1677 | 08:17:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:17:35 INFO - PROCESS | 1677 | RSS: Main: 326172672 08:17:35 INFO - PROCESS | 1677 | 08:17:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:17:38 INFO - PROCESS | 1677 | RSS: Main: 323850240 08:17:38 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 08:17:40 INFO - PROCESS | 1677 | RSS: Main: 370438144 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | __start_tp_report 08:17:40 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 08:17:40 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 08:17:40 INFO - PROCESS | 1677 | |i|pagename|runs| 08:17:40 INFO - PROCESS | 1677 | |0;ai-astar;112;107;113;112;112;112;113;111;111;112 08:17:40 INFO - PROCESS | 1677 | |1;audio-beat-detection;172;149;141;148;122;142;142;118;171;136 08:17:40 INFO - PROCESS | 1677 | |2;audio-dft;183;186;183;185;182;187;184;184;186;181 08:17:40 INFO - PROCESS | 1677 | |3;audio-fft;83;74;86;113;58;56;64;56;96;57 08:17:40 INFO - PROCESS | 1677 | |4;audio-oscillator;94;70;102;70;71;71;97;71;69;102 08:17:40 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;85;88;87;87;85;88;85;88;85 08:17:40 INFO - PROCESS | 1677 | |6;imaging-darkroom;94;95;95;94;94;94;94;95;93;95 08:17:40 INFO - PROCESS | 1677 | |7;imaging-desaturate;85;86;84;87;85;83;83;83;84;84 08:17:40 INFO - PROCESS | 1677 | |8;json-parse-financial;55;56;57;55;56;57;58;76;58;55 08:17:40 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;67;73;69;77;72;71;69;71;70;71 08:17:40 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;88;87;84;88;74;84;92;107;102;82 08:17:40 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;144;147;132;151;155;145;135;136;172;163 08:17:40 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;173;221;214;162;168;164;163;163;164;164 08:17:40 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;65;67;66;67;66;64;68;78;81;81 08:17:40 INFO - PROCESS | 1677 | __end_tp_report 08:17:40 INFO - PROCESS | 1677 | __start_cc_report 08:17:40 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2041 08:17:40 INFO - PROCESS | 1677 | __end_cc_report 08:17:40 INFO - PROCESS | 1677 | __startTimestamp1464103060817__endTimestamp 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | ------- Summary: start ------- 08:17:40 INFO - PROCESS | 1677 | Number of tests: 14 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:111.50 Median:112.00 stddev:1.72 (1.5%) stddev-sans-first:1.81 08:17:40 INFO - PROCESS | 1677 | Values: 112.0 107.0 113.0 112.0 112.0 112.0 113.0 111.0 111.0 112.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:144.10 Median:145.00 stddev:17.62 (12.2%) stddev-sans-first:15.53 08:17:40 INFO - PROCESS | 1677 | Values: 172.0 149.0 141.0 148.0 122.0 142.0 142.0 118.0 171.0 136.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.91 (1.0%) stddev-sans-first:1.99 08:17:40 INFO - PROCESS | 1677 | Values: 183.0 186.0 183.0 185.0 182.0 187.0 184.0 184.0 186.0 181.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:74.30 Median:69.00 stddev:19.78 (28.7%) stddev-sans-first:20.73 08:17:40 INFO - PROCESS | 1677 | Values: 83.0 74.0 86.0 113.0 58.0 56.0 64.0 56.0 96.0 57.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:71.00 stddev:14.86 (20.9%) stddev-sans-first:15.08 08:17:40 INFO - PROCESS | 1677 | Values: 94.0 70.0 102.0 70.0 71.0 71.0 97.0 71.0 69.0 102.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.43 (1.6%) stddev-sans-first:1.42 08:17:40 INFO - PROCESS | 1677 | Values: 88.0 85.0 88.0 87.0 87.0 85.0 88.0 85.0 88.0 85.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:17:40 INFO - PROCESS | 1677 | Values: 94.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 93.0 95.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:1.35 (1.6%) stddev-sans-first:1.41 08:17:40 INFO - PROCESS | 1677 | Values: 85.0 86.0 84.0 87.0 85.0 83.0 83.0 83.0 84.0 84.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:57.00 stddev:6.33 (11.1%) stddev-sans-first:6.60 08:17:40 INFO - PROCESS | 1677 | Values: 55.0 56.0 57.0 55.0 56.0 57.0 58.0 76.0 58.0 55.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:2.71 (3.8%) stddev-sans-first:2.46 08:17:40 INFO - PROCESS | 1677 | Values: 67.0 73.0 69.0 77.0 72.0 71.0 69.0 71.0 70.0 71.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:85.50 stddev:9.61 (11.2%) stddev-sans-first:10.19 08:17:40 INFO - PROCESS | 1677 | Values: 88.0 87.0 84.0 88.0 74.0 84.0 92.0 107.0 102.0 82.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:149.00 stddev:12.71 (8.5%) stddev-sans-first:13.40 08:17:40 INFO - PROCESS | 1677 | Values: 144.0 147.0 132.0 151.0 155.0 145.0 135.0 136.0 172.0 163.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:166.00 stddev:22.38 (13.5%) stddev-sans-first:23.71 08:17:40 INFO - PROCESS | 1677 | Values: 173.0 221.0 214.0 162.0 168.0 164.0 163.0 163.0 164.0 164.0 08:17:40 INFO - PROCESS | 1677 | 08:17:40 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:67.50 stddev:6.83 (10.1%) stddev-sans-first:6.97 08:17:40 INFO - PROCESS | 1677 | Values: 65.0 67.0 66.0 67.0 66.0 64.0 68.0 78.0 81.0 81.0 08:17:40 INFO - PROCESS | 1677 | -------- Summary: end -------- 08:17:40 INFO - PROCESS | 1677 | 08:17:41 INFO - TEST-INFO | 1677: exit 0 08:17:41 INFO - TEST-OK | kraken | took 59744ms 08:17:41 INFO - SUITE-END | took 1175s 08:17:41 INFO - Completed test suite (00:19:36) 08:17:41 INFO - javascript benchmark 08:17:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3450.2926899985505, 3459.7232221422287, 3486.755927085105, 3489.7382696297777, 3527.082958430807, 5583.5254003409755, 5592.692401917903, 5664.660120392779, 5681.744321505532, 5714.342850572186, 5664.745086471108, 5678.971605141979, 5720.170575266584, 5806.158107074471, 5819.970900145504, 5165.535101840834, 5176.55999240065, 5254.92117618236, 5255.395629502599, 5342.90470453558, 5304.655197412175, 5305.708186049766, 5338.546223571001, 5361.383440904307, 5396.0, 5138.177891537355, 5191.117510023305, 5213.957208558285, 5238.842834714965, 5249.658772179834, 5188.610854185951, 5195.636305458619, 5229.320188375525, 5230.947690523084, 5234.555062819665, 5211.609129315279, 5228.55557277628, 5257.211418287249, 5264.394594621629, 5299.920501192485, 5525.11598144299, 5585.888282234373, 5649.0396632572565, 5686.658800471985, 5758.942410575883, 6207.34822843604, 6259.655718935457, 6270.843228919268, 6274.372562743689, 6277.780277690259, 5503.834884953458, 6322.304546499881, 6328.905066424008, 6358.682065896733, 6360.7137678804565, 5701.0308247598, 5748.166515855261, 5770.278715160647, 5813.79651712188, 5816.30573628305, 6323.652199129, 6330.113784070234, 6331.081993110973, 6332.366763323631, 6349.2698339690605, 6190.690465476709, 6203.410675985774, 6209.534284928558, 6225.159603453476, 6228.28374736902, 6141.578063794151, 6194.132821405007, 6265.530085243625, 6279.686015699197, 6304.401081897213, 6189.50483961282, 6237.812865614039, 6238.251409830758, 6240.126382306481, 6261.843453913689, 6217.285012223652, 6255.218097737783, 6276.089966954766, 6291.276503202099, 6305.306416294204, 4887.535684110004, 4934.358533390637, 4944.554990050912, 4975.825846095369, 4975.850724478271, 4448.110377924428, 4482.036362182146, 4483.30508771141, 4483.686141970096, 4497.527759585262, 5629.802956896489, 5644.520215781622, 5645.407232240637, 5666.206731057655, 5721.5136713379825, 6267.091271765568, 6273.912762262976, 6302.275238347649, 6312.274088479883, 6316.684165791784, 5683.346415162309, 5685.118806584986, 5695.40198279183, 5698.515626171739, 5741.598088133791, 5266.420693723688, 5323.095073837457, 5352.491513306308, 5356.758945847446, 5417.349918009785, 6295.999999999908, 6354.872902542016, 6369.522285828489, 6380.617162970237, 6386.712597933197, 6268.467180289544, 6317.684115794284, 6333.429991300805, 6338.556301058881, 6348.0, 6249.187605611241, 6256.937430625818, 6299.937000629935, 6383.840403989938, 6630.171228596426, 5154.433012368561, 6198.144226620478, 6220.564560480723, 6235.220597425322, 6237.875242495215, 5228.790848366023, 6149.446549810538, 6178.41305076017, 6223.253209614875, 6279.215098112736, 6066.969665151646, 6091.02543593023, 6103.542234332354, 6120.265568131853, 6121.173641558333, 6077.24034495688, 6096.859313929741, 6115.021596544531, 6117.143599895929, 6132.601380910227, 5883.94116058834, 5903.557233207441, 5967.343592204941, 5967.8209653710455, 6050.878982420415, 6153.077038444269, 6157.384261573807, 6160.0, 6171.0, 6190.690465476799, 5845.240118784531, 6001.159837622649, 6008.279006519246, 6021.0, 6057.18228039209], "value": 5720.296010110022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.8136336147222, 497.16972655665035, 498.3969397029594, 500.05240070067225, 503.50153348185285, 30529.0, 43906.78046609766, 44294.7785261074, 44903.326450103275, 46904.76547617257, 39613.8019309903, 45710.0, 46012.3098153528, 46285.305720414224, 46306.30540541886, 8255.669773209065, 9119.270458363315, 9709.126178643939, 9870.851937220947, 9925.057119573665, 8764.0, 11800.881991180107, 12066.457009434553, 12084.818727719092, 12242.81635775464, 11857.7628447431, 12055.939720301387, 12141.93929030358, 12216.511339546407, 12238.632841014783, 8763.430377025474, 11993.0, 12056.63830085099, 12220.022398208122, 12263.93868030663, 11794.410279485992, 11865.169438139334, 12005.159638825286, 12077.758444831054, 12129.696757581043, 42043.73868783942, 42583.787081064394, 42994.57005429937, 43038.78480607608, 43200.0, 3139.780215384924, 3241.0, 3373.8481768320485, 3417.6924076832956, 3586.4261718125235, 29115.272118197216, 30359.392812143633, 31386.05841824749, 31396.686033139606, 31536.369272614647, 2714.7556719895106, 2727.7954153438573, 2823.661160660734, 2836.5461526155923, 2871.296532349567, 509156.0, 510575.0, 512972.0, 513453.9999999963, 513495.0, 64584.677076614316, 64681.0, 65278.673606631666, 66861.99707004399, 67089.6582068356, 68280.6585967077, 70492.0, 70546.0, 70555.29444705487, 70685.75885446889, 16896.915515422344, 17060.658786824442, 17258.050807205724, 17486.863353881956, 17513.7372939406, 11849.34828584419, 12213.938930305469, 12516.74966500665, 13049.934750326378, 13236.13965092266, 18018.549536261435, 18589.44231673052, 18657.813421865878, 18666.719999200024, 18747.718784218247, 6149.354317796657, 6469.288378278386, 6850.725970961105, 6852.965735171292, 6983.127109111361, 952.8808898887639, 956.4739393333639, 988.5847943863595, 1047.0628787235535, 1054.7995880782628, 28743.56884646732, 28913.132606021853, 29281.853590732335, 29328.0, 29361.265968350534, 1083.3554035348955, 1088.6407485529915, 1131.6491887514896, 1150.045462266317, 1162.2096974057722, 14638.487652932095, 14953.327100280296, 14976.326065327086, 15009.474668386554, 15417.922910385376, 538991.0, 545117.0, 545467.0, 545527.0, 545605.0, 59953.0, 60162.699186503785, 60197.0, 60212.0, 60222.0, 1155.3356819828632, 1310.0633047371175, 1432.534426311432, 1446.7034257977089, 1469.889758268134, 29696.851515742284, 30137.849310753307, 30292.24269393283, 30383.848080759453, 30492.894342832486, 53872.73063634657, 54271.0, 54960.0, 55008.449915500336, 55059.62350941259, 528518.2166802426, 546630.0, 547144.264278692, 547758.0, 547936.0, 35454.0, 40945.59054409418, 41090.38364424596, 41314.79342603268, 42687.57312426836, 46287.0, 46996.82507937192, 48503.0, 48591.757041214565, 48700.025999480524, 28563.714362856106, 32683.0, 39188.41217381799, 40296.0, 40686.593134069466, 38914.6108538911, 38965.0, 39101.41347879727, 39627.60372396239, 40126.79619611416, 32420.0, 38418.0, 39007.024824379616, 39015.414768777926, 39403.2119357617, 33392.66607333896, 33881.49177762286, 34532.65467345295, 34732.305353892276, 35104.0, 21086.367408977756, 23796.762032379454, 25001.374965625277, 25684.688378045317, 25950.09174678877], "value": 20654.966865217593, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.81090251300475, 369.3868178823153, 386.99226015479707, 395.70519962627844, 407.8225971702308, 16875.831241687578, 21451.57096858064, 21782.237621683245, 21881.249500039998, 22082.779172208295, 20864.47838804031, 20893.0, 21788.782112178873, 21863.016164272605, 21897.0, 5776.104703770911, 5823.155642431845, 5854.853628659296, 5999.070144127667, 6002.669853158074, 4149.315362965122, 4365.890852728676, 5855.385184555625, 5914.378990206031, 6218.31598524162, 6489.448396886247, 6490.993895946136, 6496.187976502937, 6505.479561635081, 6516.67416629169, 6192.256929168506, 6520.673966301666, 6528.93471065288, 6547.214334279869, 6552.279249282551, 3703.2963736890174, 5313.601479889024, 5640.238567793336, 5692.686902220376, 5694.601377903548, 24916.3770905727, 25915.352116197246, 26247.868760656074, 26500.86749566259, 26621.600675989877, 1942.524081600003, 2089.4880754215164, 2092.5187206942337, 2341.8594884306826, 2430.9756902431104, 9551.426914385089, 9889.406635601887, 10125.848112278321, 10295.176385889112, 11693.415329233589, 1642.30202164081, 1685.4353791479869, 1739.6172841974744, 1807.493901707524, 1879.1731638079202, 103170.0, 120283.39858300652, 121927.0, 122468.0, 126069.36965315207, 9237.584308706124, 11401.600943966921, 11728.530858765724, 11740.58907938218, 11901.761964760657, 14652.853471465362, 14799.0, 14822.481213157704, 14835.629109272355, 14864.777028344584, 7441.0, 8587.914120858712, 8604.182602652738, 8785.912140878509, 8920.509371984474, 7091.397231235403, 8747.956260218785, 8752.124787521325, 8775.91224087751, 8908.10918908104, 4475.865724028285, 4583.541645835457, 4585.679002469795, 4620.237660786, 4661.254199328091, 1558.3221298735086, 1727.9827201727821, 1778.9525982451864, 1801.5586181385381, 1859.5258209156848, 842.6755699055908, 868.9435186712969, 883.7127933421535, 946.914777670013, 972.6936015155203, 19963.60072798536, 20816.687749683766, 20845.0, 20939.243491789784, 21117.155313787276, 903.5978989349808, 957.9473128977974, 977.9315447918577, 980.6224603527693, 1001.7095042437758, 10771.084457821173, 10943.507542160622, 11031.889681103246, 11158.665240042812, 11181.94409027966, 599403.0, 604072.0, 604765.0, 613531.0, 613630.0, 110005.0, 130182.34908825395, 135329.0, 141282.2935885314, 143316.0, 966.6954909203579, 992.3648864726581, 996.7209181429066, 1019.0573719309609, 1038.325088692356, 17798.0, 18269.17788699485, 19223.94268315256, 19289.131989060807, 19343.0, 50306.0, 50659.49340506548, 51144.0, 53245.0, 53736.462635373144, 79895.0, 80471.37878795351, 81283.59358203171, 81454.0, 81720.18279817364, 6591.472682185509, 7672.693092276247, 7937.960310198412, 7971.681132754625, 8234.135415781497, 11671.0, 12270.4478298475, 12410.441530130985, 12593.496260149857, 12672.556460523838, 10360.015798499131, 10411.271211015157, 10542.57829686804, 10599.258051936291, 10626.203034772578, 8188.344932405274, 10378.844317335397, 10473.162147028068, 10637.14902807758, 10754.784904302027, 7600.581967991813, 10595.523201455799, 10656.360618362924, 10937.781244375226, 11192.832107518556, 7951.721689741063, 7973.481723687942, 7982.6806927724565, 8145.755627330953, 8193.46742461738, 7705.383569314329, 9315.208207302456, 10452.895471045193, 10610.204234682275, 10729.088027517748], "value": 9459.21329953681, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.8048792681102, 1361.5302720561406, 1521.6043828604559, 1555.0125670199423, 1974.6643070677983, 7910.4462687611895, 8724.607392667338, 8922.330825188104, 8938.508382038994, 9105.317101217403, 8655.783605409852, 8914.598843052063, 8944.910550894489, 8949.955250223757, 8971.102889711026, 2112.9788702113015, 2186.6719992001167, 2337.100216416679, 2355.9057637694473, 2367.597508423572, 2298.0463107810237, 2340.789328960393, 2342.0631747301045, 2344.109238489372, 2344.531093781242, 2007.9397618071407, 2343.8242131840093, 2360.6459031145378, 2369.9289021329387, 2377.3462297868136, 2099.5066159452517, 2308.653701944705, 2370.502194539149, 2374.085976898889, 2378.9762102379013, 1956.4717526267827, 2393.3059412770276, 2399.748026457214, 2400.59189937711, 2401.4116541447283, 8808.911910880874, 8835.469871807647, 8878.822423551557, 8883.244924181452, 8889.911100888972, 4151.543330233671, 5422.864428389322, 5441.673499590037, 5446.537044351235, 5451.863703407407, 3384.7969121852766, 3436.759426840122, 3616.0, 3621.3119507293795, 3629.0927268182954, 1015.4821041268932, 1018.1854516386935, 1035.6064695415769, 1038.7195457226583, 1059.1526778577183, 12133.907948284785, 12143.817842732366, 12148.574799881962, 12162.452689628988, 12164.0, 4714.422307647266, 4806.567408933143, 4839.637027222902, 4866.367372241586, 4886.902261954741, 10009.599616015277, 10123.493825308706, 10124.0, 10132.088112069949, 10138.594456221816, 5386.757595908115, 5399.676019438846, 5417.133258678671, 5437.265969094122, 5442.047641662693, 4420.867373978722, 4588.105319462739, 4801.423829140525, 4943.258511223274, 4944.752762361867, 3938.3895496198134, 4006.7996600169877, 4042.656374208166, 4098.282800509899, 4121.690873184523, 1093.4259513755183, 1281.1608396500308, 1292.7802273613509, 1296.0, 1310.60026691858, 783.7068836419838, 895.0, 896.8834051573253, 937.4422218779815, 959.0744931141481, 5669.433056694297, 5805.36141024487, 5886.293644762656, 5954.702264886739, 6009.06859436788, 454.51140024473824, 456.93831332770316, 459.80688110993464, 971.7133445633414, 1020.3061917895902, 3897.279003384383, 3953.367461206251, 3967.7222594418113, 4030.9596904031173, 4146.688998325138, 8980.236679882153, 10750.78498430027, 10975.0, 10977.615783447542, 10992.395418252074, 10841.0, 10877.184211184034, 10943.890561094287, 10986.725331866768, 10986.780264394827, 8091.884624419641, 8175.754727358189, 8299.543525106177, 8485.32117430604, 8500.914990850013, 5952.5833191676165, 6014.1580178775885, 6015.218021657157, 6108.847278818065, 6140.416660417253, 10151.64469243573, 10234.437105958947, 10250.58997640086, 10282.434466104436, 10296.794064118525, 9731.805363892825, 10198.643047493302, 10219.0, 10379.740506487398, 10450.216233782648, 5713.542916566747, 6117.3270940197435, 7160.749373771893, 7346.96326518364, 7659.463837531543, 8372.204640559137, 8419.494830310201, 8526.914730852612, 8530.317574594143, 8538.975322961285, 7398.778036658909, 7538.547687138789, 7638.6020611786, 7665.69337226527, 7673.693052278069, 7321.670524826289, 7388.815279618053, 7403.888941665987, 7527.473076884566, 7564.092308922744, 6954.200266969161, 6985.615791131537, 7019.824504387431, 7022.087128673445, 7031.261717519586, 4026.8389264429093, 4858.198397264553, 4908.042931628298, 4968.0, 5119.974400128124, 4846.61227101824, 4914.778834952364, 4955.702657840541, 4965.8013679452415, 5183.041137389595], "value": 4543.553256254066, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [261.26844834463503, 304.4337532190126, 312.2505985634477, 316.93344397676475, 319.94081094997415, 4282.593153650406, 4718.363020992174, 5401.594880383977, 5414.511009472392, 5460.808871689492, 4417.293233082707, 4683.976580117103, 5360.597955153369, 5384.811531596395, 5398.784048638059, 3921.568627450978, 3974.5627980922077, 4054.6958978076614, 4087.3255912774357, 4129.380592911058, 4096.914317705796, 4102.282100632392, 4132.752034877902, 4148.502179738435, 4166.333386658135, 3425.201769071233, 3484.9128771780656, 3512.701420379271, 3600.567931848169, 4125.154343359604, 3368.79787212768, 3842.6934842153664, 3971.7886044756337, 4050.230456213325, 4112.239235741397, 3140.026591756563, 3256.127726557239, 3446.9486806524, 3551.5738111426535, 3571.8928432147077, 4387.561243875619, 5060.21566657165, 5133.5102475299445, 5149.0474262261605, 5242.266082748418, 3897.8830635080994, 4390.0, 4671.159191345557, 4683.742394168319, 4700.647451441156, 5571.60998730085, 5635.830925072254, 5647.632903861236, 5687.601867869251, 5701.28961311609, 3860.0, 3864.265789499986, 3867.45855580219, 3877.631624995649, 3878.45701601776, 5617.578681598897, 5949.494292985145, 5952.470200164913, 5969.194158788551, 5996.460318571306, 3291.720203782705, 3477.1828620274214, 3549.290141971616, 3834.2523207974177, 3891.5135608048995, 3519.856046784805, 3938.9212215755524, 3941.9802900985887, 3990.940135897964, 3992.86024989124, 3766.7551609145316, 4359.476862776423, 4631.9536804631525, 4632.536746325408, 4679.602233810097, 5743.712814359265, 5772.682502462321, 5785.566082543826, 5808.854778630483, 5828.388019258002, 1332.9466821327233, 1361.2649169448387, 1366.2349084512705, 1367.412012834471, 1372.6980064386019, 788.3850596534712, 815.1888870573817, 824.0070714788666, 832.24681663095, 838.6268110690684, 4603.363342959092, 5158.0, 5300.867478313073, 5325.6272060956135, 5329.147336426153, 5210.635255532076, 5393.514583687487, 5862.355140934494, 5897.351291358033, 5912.940870591325, 4566.246569316092, 4683.859484215479, 4912.38595175603, 5202.947970520247, 5318.654287471302, 3051.7711171662213, 3068.8185048755945, 3078.9384212316077, 3090.969090309113, 3114.0, 5812.534997200299, 5993.220881285405, 6030.48740857032, 6081.5065823012355, 6100.054491553817, 5667.838093190886, 5889.0, 5990.760369585255, 5991.221141251609, 6009.699515024231, 5107.489251074863, 5364.95383400241, 5382.219578161144, 5993.460588547052, 6032.607880487754, 5493.23094766741, 5774.48029677331, 5807.651540907559, 5856.180134781219, 5922.407759224043, 5733.483986441241, 5741.368449470639, 5767.307923049261, 5834.445727655866, 5834.94165058344, 6018.759249629966, 6028.0, 6067.24159480065, 6082.391760823883, 6098.32918378969, 3428.0802750458824, 3866.284737323604, 3937.488126543531, 3944.3886197639413, 3964.4449777030663, 5820.417958204146, 5851.765929362778, 5852.151438041546, 5855.151003104441, 5882.882342353043, 5166.922496162636, 5659.745311461076, 5707.172459993195, 5709.172170035238, 5713.94292055971, 4819.445763737215, 5068.619853510928, 5320.973395133, 5328.547073498796, 5773.942260577341, 5229.712365819916, 5575.6933368663495, 5623.971880140573, 5628.971855140698, 5631.690257035739, 4293.0769884474985, 4916.655834091579, 5456.945430545644, 5564.4713752193475, 5623.2408624835125, 4675.462321832897, 5510.669359838423, 5589.189567512769, 5599.412061733622, 5608.355039170385], "value": 4111.720161092642, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.818610788979, 657.7171816119073, 697.4246246846353, 717.2182321269817, 750.6847124207834, 14476.710465790691, 15248.085114893114, 15264.389424423038, 15280.770788438182, 15352.078875267493, 14840.851591484083, 15287.0, 15353.232338383064, 15573.454929077485, 15589.844101558981, 724.5725022236879, 907.9727608171748, 910.2717825739383, 916.8303863785188, 917.0966597901062, 726.5967388099602, 887.5961437545933, 913.7806926337697, 918.6922381002339, 923.7829110159107, 943.1605870775015, 957.2055194188841, 960.5581432541039, 963.1379914976092, 965.0976337124811, 741.7774667599698, 857.5412154497315, 918.8437965545874, 959.2278216036088, 960.4237457525463, 706.6713978000204, 707.1337611426013, 708.0582824842946, 708.6775517139689, 708.8405108850519, 13639.931800341034, 13724.862751372459, 13795.379207935666, 13821.585352439342, 13832.654183645389, 12079.637610871689, 12103.33431661267, 12190.0, 12260.712625174407, 12465.626031219077, 9374.812503749887, 10906.454677266185, 11025.44872756359, 11039.83440248397, 11476.139289553317, 3937.153511994935, 3952.170044290702, 3954.703397245218, 3982.38273067675, 4094.897627559305, 43179.13641727148, 43450.34824477604, 48039.0, 91784.54107729418, 93061.08980736646, 9194.678186263449, 9319.627214911328, 9525.714228573153, 9549.0, 9637.566309516089, 20847.166113355295, 20881.895590522254, 20914.954252287323, 20917.26789562358, 20942.162313507288, 7251.3836323912, 7355.264473552602, 7449.180590135081, 7460.0301960744755, 7530.209328020588, 3958.2281455115976, 4023.2154729827403, 4034.253663072341, 4054.2904991626347, 4130.359794231899, 2967.554866770002, 3414.7097496712563, 3525.506429099928, 3526.5062891195253, 3541.1678255609913, 618.6504624886925, 627.6077451592755, 636.9904451433232, 644.0178727440597, 645.7998020613625, 482.3054801086424, 530.7770736290768, 552.1855263486326, 577.7457918515839, 581.0209796492923, 7753.922460775433, 7765.7670269892, 7836.0, 7942.920570794334, 7955.84088318242, 474.2341119092641, 544.4881811097556, 563.4647085269011, 572.8682403047239, 594.040624391613, 3470.6529347065093, 3594.658507441789, 3753.0992561784974, 3760.7743535387963, 3818.446325282874, 92612.53693731489, 94687.0, 94822.57766133375, 95283.52358238165, 95952.52023739976, 71786.282137179, 76404.0, 77342.61328693433, 81022.59488702519, 83717.16282837215, 74078.62960685162, 74291.25708742843, 74570.25429745742, 75265.0, 75498.0, 7187.460940429384, 7195.0, 7352.96323518379, 7386.224446433047, 7394.371478424394, 46633.76683116562, 46664.53335466602, 48125.984251968504, 48311.27533087077, 49415.25877111774, 54820.0, 64975.67512162598, 66121.33878661152, 66400.6679966597, 66485.0, 9648.565814538502, 9664.661736839458, 9665.855012174963, 9841.360311579725, 9991.850122248317, 10413.854704247657, 10675.412852292888, 10732.980366865135, 10832.837507437238, 11031.282966607145, 9772.85340719904, 9965.750856228653, 10080.949595251977, 10101.747456313651, 10251.84622230682, 8788.560571971248, 9356.719298421058, 9975.0, 10145.847812282675, 10394.532246049097, 8625.913740862761, 9560.521973901416, 9759.853602196115, 9802.411855288705, 10117.29178957466, 8199.508029518249, 8268.917310826815, 8662.950976867056, 8701.695440659547, 8798.20816126526, 8278.45813592396, 8466.74599762008, 8505.319574434154, 8560.91439085601, 8581.184787445183], "value": 6606.9734802568, "unit": "score"}], "name": "dromaeo_css", "value": 7188.327270819319, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 112.0, 112.0, 112.0, 113.0, 111.0, 111.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 149.0, 141.0, 148.0, 122.0, 142.0, 142.0, 118.0, 171.0, 136.0], "value": 144.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 183.0, 185.0, 182.0, 187.0, 184.0, 184.0, 186.0, 181.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 74.0, 86.0, 113.0, 58.0, 56.0, 64.0, 56.0, 96.0, 57.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 70.0, 102.0, 70.0, 71.0, 71.0, 97.0, 71.0, 69.0, 102.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 87.0, 87.0, 85.0, 88.0, 85.0, 88.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 87.0, 85.0, 83.0, 83.0, 83.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 55.0, 56.0, 57.0, 58.0, 76.0, 58.0, 55.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 69.0, 77.0, 72.0, 71.0, 69.0, 71.0, 70.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 84.0, 88.0, 74.0, 84.0, 92.0, 107.0, 102.0, 82.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 132.0, 151.0, 155.0, 145.0, 135.0, 136.0, 172.0, 163.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 221.0, 214.0, 162.0, 168.0, 164.0, 163.0, 163.0, 164.0, 164.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 67.0, 66.0, 64.0, 68.0, 78.0, 81.0, 81.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1472.9999999999998, "alertThreshold": 2.0}]} 08:17:42 INFO - Return code: 0 08:17:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:17:42 ERROR - # TBPL SUCCESS # 08:17:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:42 INFO - 2016-05-24 08:17:42.123 screenresolution[1681:9979] starting screenresolution argv=screenresolution get 08:17:42 INFO - 2016-05-24 08:17:42.125 screenresolution[1681:9979] Display 0: 1600x1200x32@60 08:17:42 INFO - 2016-05-24 08:17:42.131 screenresolution[1682:9982] starting screenresolution argv=screenresolution list 08:17:42 INFO - Available Modes on Display 0 08:17:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:42 INFO - Intel Iris: 08:17:42 INFO - Chipset Model: Intel Iris 08:17:42 INFO - Type: GPU 08:17:42 INFO - Bus: Built-In 08:17:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:42 INFO - Vendor: Intel (0x8086) 08:17:42 INFO - Device ID: 0x0a2e 08:17:42 INFO - Revision ID: 0x0009 08:17:42 INFO - Displays: 08:17:42 INFO - Display: 08:17:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:42 INFO - Display Serial Number: 1600x1200 60 08:17:42 INFO - Main Display: Yes 08:17:42 INFO - Mirror: Off 08:17:42 INFO - Online: Yes 08:17:42 INFO - Rotation: Supported 08:17:42 INFO - Return code: 0 08:17:42 INFO - Running post-action listener: _resource_record_post_action 08:17:42 INFO - ##### 08:17:42 INFO - ##### Finished run-tests step (success) 08:17:42 INFO - ##### 08:17:42 INFO - Running post-run listener: _resource_record_post_run 08:17:42 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38020608; Write bytes: 693944320; Read time: 391; Write time: 1855 08:17:42 INFO - TinderboxPrint: CPU usage
53.8% 08:17:42 INFO - TinderboxPrint: I/O read bytes / time
38,020,608 / 391 08:17:42 INFO - TinderboxPrint: I/O write bytes / time
693,944,320 / 1,855 08:17:42 INFO - TinderboxPrint: CPU idle
2,238.9 (46.2%) 08:17:42 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 08:17:42 INFO - TinderboxPrint: CPU user
1,635.3 (33.7%) 08:17:42 INFO - TinderboxPrint: Swap in / out
434,786,304 / 0 08:17:42 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397517312; Write bytes: 399161344; Read time: 30000; Write time: 715 08:17:42 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36201984; Write bytes: 293869568; Read time: 372; Write time: 1138 08:17:42 INFO - Running post-run listener: _upload_blobber_files 08:17:42 INFO - Blob upload gear active. 08:17:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:17:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:17:42 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'] 08:17:42 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 08:17:43 INFO - (blobuploader) - INFO - Open directory for files ... 08:17:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:43 INFO - (blobuploader) - INFO - Done attempting. 08:17:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:17:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:44 INFO - (blobuploader) - INFO - Done attempting. 08:17:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:17:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:17:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:17:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:17:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:17:47 INFO - (blobuploader) - INFO - Done attempting. 08:17:47 INFO - (blobuploader) - INFO - Iteration through files over. 08:17:47 INFO - Return code: 0 08:17:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:17:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:17:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea502ddac72ee373d467f2ad5b3b5373c364796728230d22d8105012fded75633d0eb3b9648b2dcb298ceb9e6f9fa35b805b80e41e88e4d55296b6c87b109cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4dab6c41fc74f22419c8130f2e4d2045ef2110a4cb985bf5c0b7d6d5eb9cf5abeb5951d09823ca362b5fb4b3c6592b329a01db54fb5038296a8dc6aa9ca1625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d754d9eb140e27e35115d45cc866eff6e94ffa26b65bfd15466a65becd82c47ce1204b87b157326c63f1afb1f63140a138d2dd2f308a852590759cf7f0b667a"} 08:17:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:17:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:17:47 INFO - Contents: 08:17:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea502ddac72ee373d467f2ad5b3b5373c364796728230d22d8105012fded75633d0eb3b9648b2dcb298ceb9e6f9fa35b805b80e41e88e4d55296b6c87b109cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4dab6c41fc74f22419c8130f2e4d2045ef2110a4cb985bf5c0b7d6d5eb9cf5abeb5951d09823ca362b5fb4b3c6592b329a01db54fb5038296a8dc6aa9ca1625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d754d9eb140e27e35115d45cc866eff6e94ffa26b65bfd15466a65becd82c47ce1204b87b157326c63f1afb1f63140a138d2dd2f308a852590759cf7f0b667a"} 08:17:47 INFO - Running post-run listener: copy_logs_to_upload_dir 08:17:47 INFO - Copying logs to upload dir... 08:17:47 INFO - mkdir: /builds/slave/test/build/upload/logs 08:17:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.500669 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-24 08:17:47.358413) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 08:17:47.361747) ========= 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.ejfWCztIro/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.ZkcBImMLxD/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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/ea502ddac72ee373d467f2ad5b3b5373c364796728230d22d8105012fded75633d0eb3b9648b2dcb298ceb9e6f9fa35b805b80e41e88e4d55296b6c87b109cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4dab6c41fc74f22419c8130f2e4d2045ef2110a4cb985bf5c0b7d6d5eb9cf5abeb5951d09823ca362b5fb4b3c6592b329a01db54fb5038296a8dc6aa9ca1625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d754d9eb140e27e35115d45cc866eff6e94ffa26b65bfd15466a65becd82c47ce1204b87b157326c63f1afb1f63140a138d2dd2f308a852590759cf7f0b667a"} build_url:https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/WPNBcSkqSVycT0fJZSPvXA/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/ea502ddac72ee373d467f2ad5b3b5373c364796728230d22d8105012fded75633d0eb3b9648b2dcb298ceb9e6f9fa35b805b80e41e88e4d55296b6c87b109cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4dab6c41fc74f22419c8130f2e4d2045ef2110a4cb985bf5c0b7d6d5eb9cf5abeb5951d09823ca362b5fb4b3c6592b329a01db54fb5038296a8dc6aa9ca1625", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d754d9eb140e27e35115d45cc866eff6e94ffa26b65bfd15466a65becd82c47ce1204b87b157326c63f1afb1f63140a138d2dd2f308a852590759cf7f0b667a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 08:17:47.392113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 08:17:47.392755) ========= 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.ejfWCztIro/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.ZkcBImMLxD/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 08:17:47.442230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 08:17:47.442612) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 08:17:47.443043) ========= ========= Total master_lag: 0.16 =========