builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0306 starttime: 1463661030.05 results: success (0) buildid: 20160519032344 builduid: 0b3301f43abe458eb5cd4ca48e10e799 revision: 029dcd1503478771cb4a6795a6e8f1fec802c0fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.051456) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.051940) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.052221) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.070789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.071167) ========= 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.51HNQDj0yg/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.EVjNFkLDXq/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-19 05:30:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 74.2M=0s 2016-05-19 05:30:30 (74.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122790 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.400318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.400745) ========= 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.51HNQDj0yg/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.EVjNFkLDXq/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.019409 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:30.435742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 05:30:30.436093) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 029dcd1503478771cb4a6795a6e8f1fec802c0fd --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 029dcd1503478771cb4a6795a6e8f1fec802c0fd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.51HNQDj0yg/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.EVjNFkLDXq/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-19 05:30:30,503 truncating revision to first 12 chars 2016-05-19 05:30:30,503 Setting DEBUG logging. 2016-05-19 05:30:30,503 attempt 1/10 2016-05-19 05:30:30,503 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/029dcd150347?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 05:30:31,300 unpacking tar archive at: fx-team-029dcd150347/testing/mozharness/ program finished with exit code 0 elapsedTime=1.048791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 05:30:31.504718) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:31.505137) ========= script_repo_revision: 029dcd1503478771cb4a6795a6e8f1fec802c0fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:31.505578) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:31.505860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 05:30:31.520279) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-19 05:30:31.520681) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.51HNQDj0yg/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.EVjNFkLDXq/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:30:31 INFO - MultiFileLogger online at 20160519 05:30:31 in /builds/slave/test 05:30:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:30:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:30:31 INFO - {'append_to_log': False, 05:30:31 INFO - 'base_work_dir': '/builds/slave/test', 05:30:31 INFO - 'blob_upload_branch': 'Fx-Team', 05:30:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:30:31 INFO - 'branch': 'Fx-Team', 05:30:31 INFO - 'buildbot_json_path': 'buildprops.json', 05:30:31 INFO - 'config_files': ('talos/mac_config.py',), 05:30:31 INFO - 'default_actions': ('clobber', 05:30:31 INFO - 'read-buildbot-config', 05:30:31 INFO - 'download-and-extract', 05:30:31 INFO - 'populate-webroot', 05:30:31 INFO - 'create-virtualenv', 05:30:31 INFO - 'install', 05:30:31 INFO - 'run-tests'), 05:30:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:30:31 INFO - 'download_minidump_stackwalk': True, 05:30:31 INFO - 'download_symbols': 'ondemand', 05:30:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:30:31 INFO - 'tooltool.py': '/tools/tooltool.py', 05:30:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:30:31 INFO - '/tools/misc-python/virtualenv.py')}, 05:30:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:30:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:30:31 INFO - 'installer_path': 'installer.exe', 05:30:31 INFO - 'log_level': 'info', 05:30:31 INFO - 'log_name': 'talos', 05:30:31 INFO - 'log_to_console': True, 05:30:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:30:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:30:31 INFO - 'opt_config_files': (), 05:30:31 INFO - 'pip_index': False, 05:30:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:31 INFO - 'cmd': ('bash', 05:30:31 INFO - '-c', 05:30:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:31 INFO - 'enabled': True, 05:30:31 INFO - 'halt_on_failure': False, 05:30:31 INFO - 'name': 'check_screen_resolution'},), 05:30:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:31 INFO - 'cmd': ('bash', 05:30:31 INFO - '-c', 05:30:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:31 INFO - 'enabled': True, 05:30:31 INFO - 'halt_on_failure': False, 05:30:31 INFO - 'name': 'check_screen_resolution'},), 05:30:31 INFO - 'run_cmd_checks_enabled': True, 05:30:31 INFO - 'sps_profile': False, 05:30:31 INFO - 'sps_profile_interval': 0, 05:30:31 INFO - 'suite': 'dromaeojs-e10s', 05:30:31 INFO - 'system_bits': '32', 05:30:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:30:31 INFO - 'title': 't-yosemite-r7-0306', 05:30:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:30:31 INFO - 'use_talos_json': True, 05:30:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:30:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:30:31 INFO - 'work_dir': 'build'} 05:30:31 INFO - ##### 05:30:31 INFO - ##### Running clobber step. 05:30:31 INFO - ##### 05:30:31 INFO - Running pre-action listener: _resource_record_pre_action 05:30:31 INFO - Running main action method: clobber 05:30:31 INFO - rmtree: /builds/slave/test/build 05:30:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:30:33 INFO - Running post-action listener: _resource_record_post_action 05:30:33 INFO - ##### 05:30:33 INFO - ##### Finished clobber step (success) 05:30:33 INFO - ##### 05:30:33 INFO - ##### 05:30:33 INFO - ##### Running read-buildbot-config step. 05:30:33 INFO - ##### 05:30:33 INFO - Running pre-action listener: _resource_record_pre_action 05:30:33 INFO - Running main action method: read_buildbot_config 05:30:33 INFO - Using buildbot properties: 05:30:33 INFO - { 05:30:33 INFO - "project": "", 05:30:33 INFO - "product": "firefox", 05:30:33 INFO - "script_repo_revision": "production", 05:30:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:30:33 INFO - "repository": "", 05:30:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:30:33 INFO - "buildid": "20160519032344", 05:30:33 INFO - "pgo_build": "False", 05:30:33 INFO - "basedir": "/builds/slave/test", 05:30:33 INFO - "buildnumber": 676, 05:30:33 INFO - "slavename": "t-yosemite-r7-0306", 05:30:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:30:33 INFO - "platform": "yosemite_r7", 05:30:33 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:30:33 INFO - "branch": "fx-team", 05:30:33 INFO - "repo_path": "integration/fx-team", 05:30:33 INFO - "revision": "029dcd1503478771cb4a6795a6e8f1fec802c0fd", 05:30:33 INFO - "stage_platform": "macosx64", 05:30:33 INFO - "builduid": "0b3301f43abe458eb5cd4ca48e10e799", 05:30:33 INFO - "slavebuilddir": "test" 05:30:33 INFO - } 05:30:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:30:33 INFO - Running post-action listener: _resource_record_post_action 05:30:33 INFO - ##### 05:30:33 INFO - ##### Finished read-buildbot-config step (success) 05:30:33 INFO - ##### 05:30:33 INFO - ##### 05:30:33 INFO - ##### Running download-and-extract step. 05:30:33 INFO - ##### 05:30:33 INFO - Running pre-action listener: _resource_record_pre_action 05:30:33 INFO - Running main action method: download_and_extract 05:30:33 INFO - mkdir: /builds/slave/test/build/tests 05:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:33 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:30:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:34 INFO - Downloaded 1199 bytes. 05:30:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:30:34 INFO - Using the following test package requirements: 05:30:34 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:30:34 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:30:34 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:30:34 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'jsshell-mac.zip'], 05:30:34 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:30:34 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:30:34 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:30:34 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:30:34 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:30:34 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:30:34 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:30:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:34 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:30:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:35 INFO - Downloaded 18628983 bytes. 05:30:35 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'] 05:30:35 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 05:30:38 INFO - Return code: 0 05:30:38 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 05:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:38 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:40 INFO - Downloaded 18628983 bytes. 05:30: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'] 05:30: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 05:30:44 INFO - Return code: 0 05:30: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')]} 05:30:44 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:30:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:30:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:30:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:45 INFO - Downloaded 10840194 bytes. 05:30:45 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'] 05:30:45 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 05:30:45 INFO - Return code: 0 05:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:30:45 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:30:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:30:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 05:30:56 INFO - Downloaded 110308328 bytes. 05:30:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:56 INFO - mkdir: /builds/slave/test/properties 05:30:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:56 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:56 INFO - Contents: 05:30:56 INFO - build_url:https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:30:57 INFO - Running post-action listener: _resource_record_post_action 05:30:57 INFO - Running post-action listener: set_extra_try_arguments 05:30:57 INFO - ##### 05:30:57 INFO - ##### Finished download-and-extract step (success) 05:30:57 INFO - ##### 05:30:57 INFO - ##### 05:30:57 INFO - ##### Running populate-webroot step. 05:30:57 INFO - ##### 05:30:57 INFO - Running pre-action listener: _resource_record_pre_action 05:30:57 INFO - Running main action method: populate_webroot 05:30:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:30:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:30:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:30:57 INFO - '--tppagecycles', 05:30:57 INFO - '7'], 05:30:57 INFO - 'tests': ['tsvgm']}}, 05:30:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:30:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:30:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['damp', 'tps']}, 05:30:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'tests': ['damp', 'tps']}, 05:30:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['dromaeo_dom']}, 05:30:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['a11yr', 05:30:57 INFO - 'ts_paint', 05:30:57 INFO - 'tpaint', 05:30:57 INFO - 'sessionrestore', 05:30:57 INFO - 'sessionrestore_no_auto_restore', 05:30:57 INFO - 'tabpaint']}, 05:30:57 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:57 INFO - 'ts_paint', 05:30:57 INFO - 'tpaint', 05:30:57 INFO - 'sessionrestore', 05:30:57 INFO - 'sessionrestore_no_auto_restore', 05:30:57 INFO - 'tabpaint']}, 05:30:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:30:57 INFO - 'ts_paint', 05:30:57 INFO - 'tpaint', 05:30:57 INFO - 'sessionrestore', 05:30:57 INFO - 'sessionrestore_no_auto_restore', 05:30:57 INFO - 'tabpaint']}, 05:30:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:30:57 INFO - 'ts_paint', 05:30:57 INFO - 'tpaint', 05:30:57 INFO - 'sessionrestore', 05:30:57 INFO - 'sessionrestore_no_auto_restore', 05:30:57 INFO - 'tabpaint']}, 05:30:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['a11yr', 05:30:57 INFO - 'ts_paint', 05:30:57 INFO - 'tpaint', 05:30:57 INFO - 'sessionrestore', 05:30:57 INFO - 'sessionrestore_no_auto_restore', 05:30:57 INFO - 'tabpaint']}, 05:30:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['a11yr', 05:30:57 INFO - 'ts_paint', 05:30:57 INFO - 'tpaint', 05:30:57 INFO - 'sessionrestore', 05:30:57 INFO - 'sessionrestore_no_auto_restore', 05:30:57 INFO - 'tabpaint']}, 05:30:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['tsvgx', 05:30:57 INFO - 'tsvgr_opacity', 05:30:57 INFO - 'tart', 05:30:57 INFO - 'tscrollx', 05:30:57 INFO - 'cart']}, 05:30:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:57 INFO - 'tsvgr_opacity', 05:30:57 INFO - 'tart', 05:30:57 INFO - 'tscrollx', 05:30:57 INFO - 'cart']}, 05:30:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'talos_options': ['--disable-e10s'], 05:30:57 INFO - 'tests': ['tp5o']}, 05:30:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'tests': ['tp5o']}, 05:30:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'talos_options': ['--disable-e10s', 05:30:57 INFO - '--xperf_path', 05:30:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:57 INFO - 'tests': ['tp5n']}, 05:30:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:57 INFO - 'talos_options': ['--xperf_path', 05:30:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:57 INFO - 'tests': ['tp5n']}}, 05:30:57 INFO - 'talos.zip': {'path': '', 05:30:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:30:57 INFO - Running post-action listener: _resource_record_post_action 05:30:57 INFO - ##### 05:30:57 INFO - ##### Finished populate-webroot step (success) 05:30:57 INFO - ##### 05:30:57 INFO - ##### 05:30:57 INFO - ##### Running create-virtualenv step. 05:30:57 INFO - ##### 05:30:57 INFO - Running pre-action listener: _resource_record_pre_action 05:30:57 INFO - Running main action method: create_virtualenv 05:30:57 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:57 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 05:30:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:30:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:57 INFO - Using real prefix '/tools/python27' 05:30:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:58 INFO - Installing distribute.............................................................................................................................................................................................done. 05:31:01 INFO - Installing pip.................done. 05:31:01 INFO - Return code: 0 05:31:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:31:01 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')]} 05:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:01 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')]} 05:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:01 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:01 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 05:31:01 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 05:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:01 INFO - 'HOME': '/Users/cltbld', 05:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:01 INFO - 'LOGNAME': 'cltbld', 05:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:01 INFO - 'MOZ_NO_REMOTE': '1', 05:31:01 INFO - 'NO_EM_RESTART': '1', 05:31:01 INFO - 'PAGER': '/bin/cat', 05:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:01 INFO - 'PWD': '/builds/slave/test', 05:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:01 INFO - 'SHELL': '/bin/bash', 05:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:01 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:01 INFO - 'USER': 'cltbld', 05:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:01 INFO - 'XPC_FLAGS': '0x0', 05:31:01 INFO - 'XPC_SERVICE_NAME': '0', 05:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:01 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 05:31:01 INFO - Cleaning up... 05:31:01 INFO - Return code: 0 05:31:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:31:01 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')]} 05:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:01 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')]} 05:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:01 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:01 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 05:31:01 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 05:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:01 INFO - 'HOME': '/Users/cltbld', 05:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:01 INFO - 'LOGNAME': 'cltbld', 05:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:01 INFO - 'MOZ_NO_REMOTE': '1', 05:31:01 INFO - 'NO_EM_RESTART': '1', 05:31:01 INFO - 'PAGER': '/bin/cat', 05:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:01 INFO - 'PWD': '/builds/slave/test', 05:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:01 INFO - 'SHELL': '/bin/bash', 05:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:01 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:01 INFO - 'USER': 'cltbld', 05:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:01 INFO - 'XPC_FLAGS': '0x0', 05:31:01 INFO - 'XPC_SERVICE_NAME': '0', 05:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:01 INFO - Downloading/unpacking psutil>=3.1.1 05:31:01 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 05:31:01 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 05:31:01 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 05:31:01 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 05:31:01 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 05:31:01 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 05:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:31:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:04 INFO - Installing collected packages: psutil 05:31:04 INFO - Running setup.py install for psutil 05:31:04 INFO - building 'psutil._psutil_osx' extension 05:31:04 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 05:31:04 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 05:31:04 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 05:31:05 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 05:31:05 INFO - building 'psutil._psutil_posix' extension 05:31:05 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 05:31:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:31:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:31:05 INFO - ^ 05:31:05 INFO - 1 warning generated. 05:31:05 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 05:31:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:31:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:31:05 INFO - Successfully installed psutil 05:31:05 INFO - Cleaning up... 05:31:05 INFO - Return code: 0 05:31:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:05 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:05 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 05:31:05 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 05:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:05 INFO - 'HOME': '/Users/cltbld', 05:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:05 INFO - 'LOGNAME': 'cltbld', 05:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:05 INFO - 'MOZ_NO_REMOTE': '1', 05:31:05 INFO - 'NO_EM_RESTART': '1', 05:31:05 INFO - 'PAGER': '/bin/cat', 05:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:05 INFO - 'PWD': '/builds/slave/test', 05:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:05 INFO - 'SHELL': '/bin/bash', 05:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:05 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:05 INFO - 'USER': 'cltbld', 05:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:05 INFO - 'XPC_FLAGS': '0x0', 05:31:05 INFO - 'XPC_SERVICE_NAME': '0', 05:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:31:05 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 05:31:05 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 05:31:05 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 05:31:05 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 05:31:05 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 05:31:05 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 05:31:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:31:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:31:08 INFO - Installing collected packages: mozsystemmonitor 05:31:08 INFO - Running setup.py install for mozsystemmonitor 05:31:08 INFO - Successfully installed mozsystemmonitor 05:31:08 INFO - Cleaning up... 05:31:08 INFO - Return code: 0 05:31:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:08 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:08 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 05:31:08 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 05:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:08 INFO - 'HOME': '/Users/cltbld', 05:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:08 INFO - 'LOGNAME': 'cltbld', 05:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:08 INFO - 'MOZ_NO_REMOTE': '1', 05:31:08 INFO - 'NO_EM_RESTART': '1', 05:31:08 INFO - 'PAGER': '/bin/cat', 05:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:08 INFO - 'PWD': '/builds/slave/test', 05:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:08 INFO - 'SHELL': '/bin/bash', 05:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:08 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:08 INFO - 'USER': 'cltbld', 05:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:08 INFO - 'XPC_FLAGS': '0x0', 05:31:08 INFO - 'XPC_SERVICE_NAME': '0', 05:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:31:08 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 05:31:08 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 05:31:08 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 05:31:08 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 05:31:08 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 05:31:08 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 05:31:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:31:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:31:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 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 05:31:11 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 05:31:12 INFO - Downloading docopt-0.6.1.tar.gz 05:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:31:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:31:12 INFO - Running setup.py install for blobuploader 05:31:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:31:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:31:12 INFO - Running setup.py install for requests 05:31:12 INFO - Running setup.py install for docopt 05:31:13 INFO - Successfully installed blobuploader requests docopt 05:31:13 INFO - Cleaning up... 05:31:13 INFO - Return code: 0 05:31:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:13 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:13 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 05:31:13 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 05:31:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:13 INFO - 'HOME': '/Users/cltbld', 05:31:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:13 INFO - 'LOGNAME': 'cltbld', 05:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:13 INFO - 'MOZ_NO_REMOTE': '1', 05:31:13 INFO - 'NO_EM_RESTART': '1', 05:31:13 INFO - 'PAGER': '/bin/cat', 05:31:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:13 INFO - 'PWD': '/builds/slave/test', 05:31:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:13 INFO - 'SHELL': '/bin/bash', 05:31:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:13 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:13 INFO - 'USER': 'cltbld', 05:31:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:13 INFO - 'XPC_FLAGS': '0x0', 05:31:13 INFO - 'XPC_SERVICE_NAME': '0', 05:31:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-TBLBdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-d62gn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BSc02p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-sm1g8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-7ha1Gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:13 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-C2iDtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-A5QVQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-DXrluh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-9yiZz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-_LvVCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rhEB9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-37Arf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-5CDS1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-glWlBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-MAqJ3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:14 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-3JILk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:15 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Bnk8PL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:31:15 INFO - Running setup.py install for manifestparser 05:31:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:31:15 INFO - Running setup.py install for mozcrash 05:31:15 INFO - Running setup.py install for mozdebug 05:31:15 INFO - Running setup.py install for mozdevice 05:31:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:31:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:31:15 INFO - Running setup.py install for mozfile 05:31:15 INFO - Running setup.py install for mozhttpd 05:31:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Running setup.py install for mozinfo 05:31:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Running setup.py install for mozInstall 05:31:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Running setup.py install for mozleak 05:31:16 INFO - Running setup.py install for mozlog 05:31:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Running setup.py install for moznetwork 05:31:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:16 INFO - Running setup.py install for mozprocess 05:31:16 INFO - Running setup.py install for mozprofile 05:31:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:17 INFO - Running setup.py install for mozrunner 05:31:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:17 INFO - Running setup.py install for mozscreenshot 05:31:17 INFO - Running setup.py install for moztest 05:31:17 INFO - Running setup.py install for mozversion 05:31:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:17 INFO - Cleaning up... 05:31:17 INFO - Return code: 0 05:31:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:17 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:17 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 05:31:17 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 05:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:17 INFO - 'HOME': '/Users/cltbld', 05:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:17 INFO - 'LOGNAME': 'cltbld', 05:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:17 INFO - 'MOZ_NO_REMOTE': '1', 05:31:17 INFO - 'NO_EM_RESTART': '1', 05:31:17 INFO - 'PAGER': '/bin/cat', 05:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:17 INFO - 'PWD': '/builds/slave/test', 05:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:17 INFO - 'SHELL': '/bin/bash', 05:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:17 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:17 INFO - 'USER': 'cltbld', 05:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:17 INFO - 'XPC_FLAGS': '0x0', 05:31:17 INFO - 'XPC_SERVICE_NAME': '0', 05:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-hkojhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-t4eBBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-YrQ_F0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-xPbDgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-P7iPdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-0gkMP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-IE1XiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CoyXBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-jhtI72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:18 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)) 05:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:18 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-JbOi3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gdHW2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BRaU8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-a5wsxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-AlV7E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-ZRd9fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-DnTE6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:19 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)) 05:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:19 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gSmR9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:19 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)) 05:31:19 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)) 05:31:19 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)) 05:31:19 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)) 05:31:19 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)) 05:31:19 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)) 05:31:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:19 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 05:31:19 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 05:31:19 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 05:31:19 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 05:31:19 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 05:31:19 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 05:31:22 INFO - Downloading blessings-1.6.tar.gz 05:31:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:22 INFO - Installing collected packages: blessings 05:31:22 INFO - Running setup.py install for blessings 05:31:22 INFO - Successfully installed blessings 05:31:22 INFO - Cleaning up... 05:31:22 INFO - Return code: 0 05:31:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:22 INFO - Reading from file tmpfile_stdout 05:31:22 INFO - Current package versions: 05:31:22 INFO - blessings == 1.6 05:31:22 INFO - blobuploader == 1.2.4 05:31:22 INFO - docopt == 0.6.1 05:31:22 INFO - manifestparser == 1.1 05:31:22 INFO - mozInstall == 1.12 05:31:22 INFO - mozcrash == 0.17 05:31:22 INFO - mozdebug == 0.1 05:31:22 INFO - mozdevice == 0.48 05:31:22 INFO - mozfile == 1.2 05:31:22 INFO - mozhttpd == 0.7 05:31:22 INFO - mozinfo == 0.9 05:31:22 INFO - mozleak == 0.1 05:31:22 INFO - mozlog == 3.2 05:31:22 INFO - moznetwork == 0.27 05:31:22 INFO - mozprocess == 0.22 05:31:22 INFO - mozprofile == 0.28 05:31:22 INFO - mozrunner == 6.11 05:31:22 INFO - mozscreenshot == 0.1 05:31:22 INFO - mozsystemmonitor == 0.3 05:31:22 INFO - moztest == 0.7 05:31:22 INFO - mozversion == 1.4 05:31:22 INFO - psutil == 3.1.1 05:31:22 INFO - requests == 1.2.3 05:31:22 INFO - wsgiref == 0.1.2 05:31:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31: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')]} 05:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31: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')]} 05:31:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:22 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:22 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 05:31:22 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 05:31:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:22 INFO - 'HOME': '/Users/cltbld', 05:31:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:22 INFO - 'LOGNAME': 'cltbld', 05:31:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:22 INFO - 'MOZ_NO_REMOTE': '1', 05:31:22 INFO - 'NO_EM_RESTART': '1', 05:31:22 INFO - 'PAGER': '/bin/cat', 05:31:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:22 INFO - 'PWD': '/builds/slave/test', 05:31:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:22 INFO - 'SHELL': '/bin/bash', 05:31:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:22 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:22 INFO - 'USER': 'cltbld', 05:31:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:22 INFO - 'XPC_FLAGS': '0x0', 05:31:22 INFO - 'XPC_SERVICE_NAME': '0', 05:31:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 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)) 05:31:23 INFO - Cleaning up... 05:31:23 INFO - Return code: 0 05:31:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:23 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:23 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 05:31:23 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 05:31:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:23 INFO - 'HOME': '/Users/cltbld', 05:31:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:23 INFO - 'LOGNAME': 'cltbld', 05:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:23 INFO - 'MOZ_NO_REMOTE': '1', 05:31:23 INFO - 'NO_EM_RESTART': '1', 05:31:23 INFO - 'PAGER': '/bin/cat', 05:31:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:23 INFO - 'PWD': '/builds/slave/test', 05:31:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:23 INFO - 'SHELL': '/bin/bash', 05:31:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:23 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:23 INFO - 'USER': 'cltbld', 05:31:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:23 INFO - 'XPC_FLAGS': '0x0', 05:31:23 INFO - 'XPC_SERVICE_NAME': '0', 05:31:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:23 INFO - Downloading/unpacking jsonschema 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:31:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31: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 05:31:26 INFO - Downloading functools32-3.2.3-2.tar.gz 05:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:31:26 INFO - warning: no files found matching '*.txt' 05:31:26 INFO - no previously-included directories found matching 'build' 05:31:26 INFO - no previously-included directories found matching 'dist' 05:31:26 INFO - no previously-included directories found matching '.git*' 05:31:26 INFO - Installing collected packages: jsonschema, functools32 05:31:26 INFO - Running setup.py install for functools32 05:31:26 INFO - warning: no files found matching '*.txt' 05:31:26 INFO - no previously-included directories found matching 'build' 05:31:26 INFO - no previously-included directories found matching 'dist' 05:31:26 INFO - no previously-included directories found matching '.git*' 05:31:26 INFO - Successfully installed jsonschema functools32 05:31:26 INFO - Cleaning up... 05:31:26 INFO - Return code: 0 05:31:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:31:26 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')]} 05:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:27 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')]} 05:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:27 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 0x10ac0ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac12570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee39d82410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, '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.51HNQDj0yg/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.EVjNFkLDXq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 05:31:27 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 05:31:27 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 05:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:27 INFO - 'HOME': '/Users/cltbld', 05:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:27 INFO - 'LOGNAME': 'cltbld', 05:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:27 INFO - 'MOZ_NO_REMOTE': '1', 05:31:27 INFO - 'NO_EM_RESTART': '1', 05:31:27 INFO - 'PAGER': '/bin/cat', 05:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:27 INFO - 'PWD': '/builds/slave/test', 05:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:27 INFO - 'SHELL': '/bin/bash', 05:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:31:27 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:27 INFO - 'USER': 'cltbld', 05:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:27 INFO - 'XPC_FLAGS': '0x0', 05:31:27 INFO - 'XPC_SERVICE_NAME': '0', 05:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:27 INFO - Downloading/unpacking flake8 05:31:27 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 05:31:27 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 05:31:27 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 05:31:27 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 05:31:27 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 05:31:27 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 05:31:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:31:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:31:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:31 INFO - no previously-included directories found matching 'docs/_build' 05:31:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:31:31 INFO - Running setup.py install for pep8 05:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:31:31 INFO - no previously-included directories found matching 'docs/_build' 05:31:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:31:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:31:31 INFO - Cleaning up... 05:31:31 INFO - Return code: 0 05:31:31 INFO - Running post-action listener: _resource_record_post_action 05:31:31 INFO - Running post-action listener: _start_resource_monitoring 05:31:31 INFO - Starting resource monitoring. 05:31:31 INFO - ##### 05:31:31 INFO - ##### Finished create-virtualenv step (success) 05:31:31 INFO - ##### 05:31:31 INFO - ##### 05:31:31 INFO - ##### Running install step. 05:31:31 INFO - ##### 05:31:31 INFO - Running pre-action listener: _resource_record_pre_action 05:31:31 INFO - Running main action method: install 05:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:32 INFO - Reading from file tmpfile_stdout 05:31:32 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:32 INFO - Reading from file tmpfile_stdout 05:31:32 INFO - Output received: 05:31:32 INFO - Usage: mozinstall [options] installer 05:31:32 INFO - Options: 05:31:32 INFO - -h, --help show this help message and exit 05:31:32 INFO - -d DEST, --destination=DEST 05:31:32 INFO - Directory to install application into. [default: 05:31:32 INFO - "/builds/slave/test"] 05:31:32 INFO - --app=APP Application being installed. [default: firefox] 05:31:32 INFO - mkdir: /builds/slave/test/build/application 05:31:32 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'] 05:31:32 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 05:32:05 INFO - Reading from file tmpfile_stdout 05:32:05 INFO - Output received: 05:32:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:32:05 INFO - Running post-action listener: _resource_record_post_action 05:32:05 INFO - ##### 05:32:05 INFO - ##### Finished install step (success) 05:32:05 INFO - ##### 05:32:05 INFO - ##### 05:32:05 INFO - ##### Running run-tests step. 05:32:05 INFO - ##### 05:32:05 INFO - Running pre-action listener: _resource_record_pre_action 05:32:05 INFO - Running main action method: run_tests 05:32:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:32:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:32:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:32:05 INFO - 2016-05-19 05:32:05.875 screenresolution[2544:30658] starting screenresolution argv=screenresolution get 05:32:05 INFO - 2016-05-19 05:32:05.878 screenresolution[2544:30658] Display 0: 1600x1200x32@60 05:32:05 INFO - 2016-05-19 05:32:05.884 screenresolution[2545:30660] starting screenresolution argv=screenresolution list 05:32:05 INFO - Available Modes on Display 0 05:32:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:32:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:32:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:32:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:32:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:32:06 INFO - Intel Iris: 05:32:06 INFO - Chipset Model: Intel Iris 05:32:06 INFO - Type: GPU 05:32:06 INFO - Bus: Built-In 05:32:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:32:06 INFO - Vendor: Intel (0x8086) 05:32:06 INFO - Device ID: 0x0a2e 05:32:06 INFO - Revision ID: 0x0009 05:32:06 INFO - Displays: 05:32:06 INFO - Display: 05:32:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:32:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:32:06 INFO - Display Serial Number: 1600x1200 60 05:32:06 INFO - Main Display: Yes 05:32:06 INFO - Mirror: Off 05:32:06 INFO - Online: Yes 05:32:06 INFO - Rotation: Supported 05:32:06 INFO - Return code: 0 05:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:32:06 INFO - Python 2.7.3 05:32:06 INFO - Return code: 0 05:32:06 INFO - grabbing minidump binary from tooltool 05:32:06 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')]} 05:32:06 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 0x10aab0d30>, '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 0x10a8d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aaa7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:32:06 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 05:32:06 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 05:32:06 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 05:32:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:32:06 INFO - Return code: 0 05:32:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:32:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:32:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:32:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:32:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:32:07 INFO - Return code: 0 05:32:07 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:32:07 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.51HNQDj0yg/Render', 05:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:07 INFO - 'HOME': '/Users/cltbld', 05:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:07 INFO - 'LOGNAME': 'cltbld', 05:32:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:32:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:07 INFO - 'MOZ_NO_REMOTE': '1', 05:32:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:32:07 INFO - 'NO_EM_RESTART': '1', 05:32:07 INFO - 'PAGER': '/bin/cat', 05:32:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:07 INFO - 'PWD': '/builds/slave/test', 05:32:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:07 INFO - 'SHELL': '/bin/bash', 05:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EVjNFkLDXq/Listeners', 05:32:07 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 05:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:07 INFO - 'USER': 'cltbld', 05:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:07 INFO - 'XPC_FLAGS': '0x0', 05:32:07 INFO - 'XPC_SERVICE_NAME': '0', 05:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:32:07 INFO - mozversion application_buildid: 20160519032344 05:32:07 INFO - mozversion application_changeset: 029dcd1503478771cb4a6795a6e8f1fec802c0fd 05:32:07 INFO - mozversion application_display_name: Nightly 05:32:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:32:07 INFO - mozversion application_name: Firefox 05:32:07 INFO - mozversion application_remotingname: firefox 05:32:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:32:07 INFO - mozversion application_vendor: Mozilla 05:32:07 INFO - mozversion application_version: 49.0a1 05:32:07 INFO - mozversion platform_buildid: 20160519032344 05:32:07 INFO - mozversion platform_changeset: 029dcd1503478771cb4a6795a6e8f1fec802c0fd 05:32:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:32:07 INFO - mozversion platform_version: 49.0a1 05:32:07 INFO - using testdate: 1463661127 05:32:07 INFO - actual date: 1463661127 05:32:07 INFO - starting webserver on 'localhost:58480' 05:32:07 INFO - SUITE-START | Running 2 tests 05:32:07 INFO - TEST-START | dromaeo_css 05:32:07 INFO - operating with platform_type : mac_ 05:32:07 INFO - Initialising browser for dromaeo_css test... 05:32:07 INFO - TEST-INFO | started process 2563 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpvEmejg/profile http://localhost:58480/getInfo.html) 05:32:10 INFO - PROCESS | 2563 | __metrics Screen width/height:1600/1200 05:32:10 INFO - PROCESS | 2563 | colorDepth:24 05:32:10 INFO - PROCESS | 2563 | Browser inner width/height: 1024/689 05:32:10 INFO - PROCESS | 2563 | __metrics 05:32:10 ERROR - PROCESS | 2563 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:32:11 INFO - TEST-INFO | 2563: exit 0 05:32:11 INFO - Browser initialized. 05:32:11 INFO - Running cycle 1/1 for dromaeo_css test... 05:32:11 INFO - TEST-INFO | started process 2566 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpvEmejg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:23 INFO - PROCESS | 2566 | RSS: Main: 249753600 05:32:23 INFO - PROCESS | 2566 | 05:33:12 INFO - PROCESS | 2566 | 1463661192936 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] 05:35:24 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:58480/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58480/tests/dromaeo/cssquery-ext.html) 05:35:25 INFO - PROCESS | 2566 | RSS: Main: 261591040 05:35:25 INFO - PROCESS | 2566 | 05:38:27 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:58480/tests/dromaeo/cssquery-ext.html (next: http://localhost:58480/tests/dromaeo/cssquery-jquery.html) 05:38:28 INFO - PROCESS | 2566 | RSS: Main: 273342464 05:38:28 INFO - PROCESS | 2566 | 05:41:30 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:58480/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58480/tests/dromaeo/cssquery-mootools.html) 05:41:30 INFO - PROCESS | 2566 | RSS: Main: 275329024 05:41:30 INFO - PROCESS | 2566 | 05:44:32 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:58480/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58480/tests/dromaeo/cssquery-prototype.html) 05:44:33 INFO - PROCESS | 2566 | RSS: Main: 276897792 05:44:33 INFO - PROCESS | 2566 | 05:47:36 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:58480/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58480/tests/dromaeo/cssquery-yui.html) 05:47:36 INFO - PROCESS | 2566 | RSS: Main: 279744512 05:47:36 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | Cycle 1(1): loaded http://localhost:58480/tests/dromaeo/cssquery-yui.html (next: http://localhost:58480/tests/dromaeo/cssquery-dojo.html) 05:50:39 INFO - PROCESS | 2566 | RSS: Main: 280956928 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | __start_tp_report 05:50:39 INFO - PROCESS | 2566 | _x_x_mozilla_page_load 05:50:39 INFO - PROCESS | 2566 | _x_x_mozilla_page_load_details 05:50:39 INFO - PROCESS | 2566 | |i|pagename|runs| 05:50:39 INFO - PROCESS | 2566 | |0;dojo.html;2927.3706153177063;3468.4450487921936;3490.2670439207764;3499.1077275294797;3523.5067090607317;5868.677222752753;5939.0794426863795;5994.220751302325;6030.125631783389;6057.606255593389;5020.6234532410035;5906.675132867691;5935.614185077978;5967.88064238716;6002.339742628318;5263.657862238952;5283.207518872162;5338.519533242007;5387.542058924996;5430.972845135769;5355.705436201008;5442.074847275952;5475.561955043607;5500.724963751816;5530.364008139075;5186.844394668147;5263.184206448007;5285.471452854722;5349.973250133763;5374.328208973879;4411.382406463098;5236.7905283788605;5319.308489896327;5334.27987221724;5412.296401467824;5308.575313974873;5357.223202635635;5385.0037743017665;5409.26974858393;5423.755930983076;5596.468335508121;5673.375928647887;5775.5668324875805;5776.9422305776825;5796.739146738407;6274.466670332981;6319.115323854664;6320.778772742978;6328.968355158195;6332.715027823759;6228.065790131472;6257.655828929407;6329.208848893888;6347.746090156388;6358.237011558596;6021.066734656135;6105.269434892363;6107.643995706967;6111.682900513584;6120.629100637178;6476.449501792306;6486.4486518646445;6505.219373675094;6511.0558968949235;6612.07430959666;5402.91895621566;6194.4115309045565;6275;6356.523260755369;6362.459190968727;6343.524235682342;6357.078223657544;6362.236531616236;6375.585586936834;6376.808695739135;6117.15760190703;6200.3179650238435;6232.127502149703;6281.842953926189;6313.084602732578;6250.562460627803;6255.718492667841;6288.8427789304715;6317.747290108344;6354.586951848115;4289.463817022872;5101.8724531887;5131.4868513148385;5134.383873935077;5151.665141765773;4050.6556942659613;4070.9415551956113;4540.750258735735;4590.449146102489;4620.745858977789;5827.35899051104;5848.5321174305955;5878.500327472213;6016.338202797689;6042.033274676119;6322.209723784527;6333.050042493571;6333.113364129025;6343.365663433619;6368.363163683594;5870.73581688825;5915.645061296336;5931.169636250928;5951.4048595141;5957.285125784847;4557.886052848706;5453.454654534515;5486.423925487846;5541.5289700375915;5578.581606379537;5513;6445.838854028687;6450.193725784277;6490.870182596322;6512.804615861532;5473.2063850742215;6311.849152778658;6312.337204593451;6319.05214217871;6339.270983836917;6348.77779277723;6410.294867564471;6442;6465.838354041187;6559.0817285579105;6295.811125666238;6295.874082518416;6302.369763023661;6333.398327158912;6334.33489483616;6168.938310616836;6309.148264984352;6387.68061596909;6388.425041746265;6392.584482008655;6144.678894037804;6193.969030155001;6195.783147589813;6246.656433896179;6249.7187626557825;4600.539946005507;6169.6915154241215;6192.566520343533;6192.628442293477;6298.244210694746;6075.696215189135;6158.692065396802;6182.845428864314;6237.126802247598;6267.46726528232;6137.5302083125;6259.749610015549;6271.31015588294;6302.590331628429;6350.174477317919;6258.123862659322;6266.561340706289;6288.779892703734;6290.559660823881;6330.335314791881 05:50:39 INFO - PROCESS | 2566 | |1;ext.html;487.54414622328125;505.10596244646973;512.2060805751084;540.5729473715763;560.694421540261;39669.80165099174;42683.78658106709;42716.78641606795;42728.35907461383;42851;42651.57348426515;43224.13551728964;43227.35158972618;43450.782746086305;43613.345799813025;9689.982551832045;9887.752806179831;9891.455969921652;9910.306278560467;9948.6517971871;9734.464604446752;9809.65666201683;9822.066903644143;9832.950835245814;9863.556139973718;8836.890845756721;9820.950895245514;9840.753981150458;9860.556274967608;9879.308448408614;8373.371997100241;9768.706938791847;9826.312158148932;9828.508574571279;9857.211423086135;9669.371490853142;9753.122219000254;9756.853647195227;9800.11798938099;9800.705978820646;40042.59957400447;40177.794666160065;40476.190476190604;40601.79699101485;40783.388249176285;2713.7150599187;2830.660320761501;3070.5742463453844;3213.373392188524;3590.605033446319;24449.633255501183;27592.034278800347;28097.01660441895;28262.434751305063;28527.714722852714;2656.7874570034546;2669.159214847317;2699.4061306512535;2769.903053393142;2913.980106962546;540688;542275;545877.2706136483;546578.999999996;549318;35704;36566.817165914;36620.63379366226;65532.344676552624;66083;37222.06944826347;37573.06067348338;37639.4354084689;37657.81171094127;37904.431433528516;13848.792268115987;15183.468578599695;15368.615784605474;15661.451849185223;15680.843191568167;13046.086773925925;13122.60632181036;13209.273489958145;13232.603021909357;13249.602511924657;8207.95896020528;8253.463524870864;8292.709755158661;8314.168583141638;8315.625796839156;2969.9703002970127;3221.9677803222135;3415.8898358033243;3442.4492081266876;3479.565054368204;845.4166625028711;923.3951761596165;1026.7279171019686;1034.5034383495813;1040.2718097331897;26176.73823261743;28077;29812.807487700684;29834.850825745732;30358;1093.9234253602172;1130.8360287758394;1169.2984209474419;1192.3382522699985;1277.348552238365;13404.597862064154;15402.306896189699;15721.842781572266;15803;15872.52382428529;669513;689681;691608;694655;695210;64105.03842442367;64649.03026454607;64824.67587662031;64832.027519587245;65052;1445.1112565772185;1481.585156156278;1498.8501149884924;1503.3385310463361;1510;29220.56169157507;29342.413151737273;29411.85294073516;29884.252893677833;30070.24824379408;53530;53970.460295396544;54258.45741542534;54341.184882227586;54594.45405545894;506628;596621;608408;612140;612795;26897;27130.72869271282;27519.44961100727;27849.02528411496;27897.860510697315;46982.76508617435;47571.04857902892;47750;47845;49386.506134938194;37362.8131859339;38224.80887595656;38557.42163867488;39185.804070979466;39487.407688884115;38204.42693359546;38375.61624383832;38489.845304640905;38673.226535469694;39176.80411597924;37931.810340949225;38735;39176.60823391729;39186.804065979486;39312;32535.837320813243;33902.660973390935;34437.48343774895;34729.65270347264;34775.13062173466;21970.89014554917;24468.409553378973;24774;26003.869980649975;26024.60963085593 05:50:39 INFO - PROCESS | 2566 | |2;jquery.html;393.9980300098501;395.1090291392909;396.9801509924504;398.70495833083527;415.8295099009405;20187.09158087886;21273.893630531846;21277.936103194857;21421.357359279245;21910.780892191073;20558.38324850253;21201;21274.255401060967;21640.783592164073;21770;5912.556558258127;6071.757129714806;6148.169997050386;6158.353372895848;6178.5366097542865;5364.597655175858;6009.669468179249;6026.578139530235;6076.088586712007;6095.786647467339;5811;5990.6106103103375;6012.3687012863675;6016.518678505709;6038.939610603904;5626.578006649518;5884.911726324109;6006.7897623583185;6034.487068599175;6045.486133678643;4869.659123861331;5269.683818970874;5545.584081193927;5824.330202026778;5867.266591676041;18549.443516694522;25223;25927;26052.218433447026;26353.868230658725;1809.972850407245;1943.6112777444425;2133.381319417367;2217.4899773052125;2436.610142377211;9940.15508681763;9949.30354875159;10001.949990250076;10454.843177352346;10580.735481613021;1526.3820350428582;1541.6510553265891;1657.9088150151738;1777.8044415114327;1877.305397003104;79756.60121699412;80388.43654291466;111578;126569;126690;11676.124290678063;11777.234479758788;11788.056955443544;11892.227005244631;11990.640280791591;15053.096814191184;15075.09549427038;15089.47186848455;15101.924490377478;15135.318910649048;8680.479171249744;8844.292456603456;8899.243564296978;8907.421017633833;8926.107389261022;8709.172628600272;8735.519546425008;8744.737657870273;8804.47173169599;8979.371443998987;4715.669903106818;4735.431748190239;4739.194336962724;4756.476787553366;4794.472608013116;1776.5558610347414;1922.1638587214607;2040.755109386883;2045.7136000959877;2050.036482853057;781.5427974634903;862.2283056664251;919.5126582911067;923.6443969071955;943.056943056943;17581.824181758275;20307;20535;20621.793782062286;21425.785742142692;883.3551507399634;914.1726737302752;918.6738707758783;953.4755884263627;960.9519524023771;11045.668629941114;11329.37688427128;11373.431328433711;11390.829137562943;11400.144989125849;584541.0772946193;589454;600668;602738;604321;78056.60971695105;123486.38256808837;128515;129669;134113;970.8446648536342;971.4705485510414;1000.4297550396204;1000.8348622477209;1003.9698809035887;18857.71713424327;18931.716024259924;19008.904955475686;19079.80920190836;19105.235790568222;42988.78505607452;46194.76104778991;52530.73734631302;52603.473965261386;53198.73400632972;73524.63237683778;76496.61751691205;77374.61312693401;77556;77755;7926.48477848938;7941.364690824605;8047.114817370201;8116.310113640407;8336.208060234268;10152;10596.788064238517;12496.87503124957;12576.245425274512;12584.370781461073;10250.436226007641;10530.157587393143;10619.734506637087;10829.350238985686;10944.671659850217;10337.534810933677;10393.324433911772;10524.631637892944;10619.734506637396;11003.17476189305;10718.678439646823;10972.835407468734;10978.725531861768;11199;11239.60661376848;6727.226368967631;8006.719764808204;8149.877751833846;8157.75526734199;8298.767568857853;9509;10047.547860346156;10553;10819.621313253998;10889.618863340062 05:50:39 INFO - PROCESS | 2566 | |3;mootools.html;1600.1199340362798;1632.9836701632985;1871.4385684294716;1928.0456174193775;1974.5853370792133;8348.206920342573;8885.378023538355;8928.821423571524;9426.151646351818;9464.242860571156;9081.137291957253;9092.909070909289;9104.499252541107;9218.723438296862;9259.166674999266;2213.7122174117344;2523.621456781488;2526.5704830178915;2526.621006848978;2529.987350063247;2478.491909158618;2534.619807028942;2535.784458321036;2548.120898290087;2552.0174732727846;2502.049221295905;2504.9373765655823;2516.6225066240117;2534.822562420631;2542.0594380079397;2467.9753202467923;2474;2487.3781554611346;2493.800495960319;2495.214007587604;2454.484558242771;2474.381404648838;2485.179890636086;2487.315988103268;2495.4759500504733;7856.332211762007;8304.916950830475;8846.557672116369;9176.99053104158;9233.49215793131;5491.203775452577;5633.211350410946;5645.491905728504;5681.204631351614;5696.3449203341315;3491.423915054013;3571.2143328467696;3736;3742.4573436851506;3761.793101379423;1049.8792638846553;1058.4337379502006;1062.713067471786;1064.5050051725984;1065.5737704918095;12296.262224266484;12448.190867593577;12454.315012674298;12459.688507787378;12482.563110291187;4256.59562341577;4980.377452818398;5016.122178618727;5122.692638441706;5124.180131178993;8879.156480134377;10558.102561282378;10558.736031599117;10571.62999295021;10599.894001060045;5579.38626751057;5630.746616402272;5683.204351390808;5693.829185124453;5696.658200507983;4439.755813430293;4640.582347588733;4957.752112394438;5134.743262836918;5179.145441002268;4219.472565929259;4223.767692776927;4231.449911511484;4254.29804082323;4314.7626880521875;1376.2224343246035;1386.031834794574;1390.4368730664098;1392.192528333564;1407.7958695989025;759.7416878261417;912.5072460871188;930.9394889332307;947.3557980573139;972.8443449048118;6036.9698151508965;6205.720742566596;6307.337729538424;6329.810105696836;6331.778387756406;454.98635040948824;458.3102430841562;472.1077164159741;481.3670023918536;481.4319103457953;4093.672506199497;4167.249895018865;4221.197972385237;4222.535521092739;4273.273543497551;10954.178436617285;11347.148963827745;11478.483468243949;11507.619085709768;11508.36703981289;11359.829602555968;11364.772704545863;11388.829167562328;11426.600068997712;11480;7976.760697179094;8469.491830490191;8470.957645211858;8608.182222688834;8613.224809767025;6058.12157237207;6155;6246.344133865879;6318.84202894913;6373.171487706569;10196.643117490852;10303.484825758533;10584.994425529563;10637.308574942605;10666.253362264568;10814.729631759268;10866.07638350749;10887.455627218766;10918.836217456903;10949.288296260718;7255.782326530212;7595.810104747205;7853.646585903763;8023.719169829028;8105.230003149692;8769.254613357936;8794.956025219833;8806.911930880866;8913.910860891308;8916.420432672116;7744.148143704076;7858.882116768367;7875.133735289228;7946.801329966797;7948.761537153895;7653.655585498777;7672.961635191788;7711.652975615999;7837.294643482114;7842.62754018056;7239.420846332176;7298.306660867209;7318.853622927618;7360.852782944418;7368.1526624438875;4571.1543364477675;4947.876303092451;5086.237064440364;5113.821016264413;5172.301739265302;5089.821856235014;5180.922286165786;5203.73981300941;5239.764210610608;5259.368875734936 05:50:39 INFO - PROCESS | 2566 | |4;prototype.html;321.1857940121791;322.205762794711;328.9013296011197;332.43486073674757;332.6523782647134;4642.396488456505;5109.233614957753;5459.33227090837;5737.971310143449;5804.984127777632;5169.588071872565;5510.448955104487;5624.465675760806;5713.971485132674;5723.597718558948;3744.794036327994;4177.268977928866;4230.682698797587;4247.723897946624;4252.2345977724;4182.372644103393;4229;4251.532331443539;4253.766042867641;4304.332828411601;3987.7806720630356;4058.736182148151;4182.762222211118;4230.2174097792;4230.894227644303;3657.0491672165163;3693.2613477304376;3746.850125994957;3921.7843018634107;4036.233115708006;2782.2627003844;2807.873645685949;2917.4602698500635;3434.4504879219203;3628.2924829658227;4917.606591472673;5375.16684913839;5380.569554435675;5404.621676482648;5429.995450841607;4141.8964525886795;4146.792660366969;4796.666316909749;4846.442659094214;4968.621337490505;5950.761969521214;5972.611780234271;5973.970130149365;5990.700464976734;5994.730237139339;4093.3450647896193;4101.651359634435;4108.404281379213;4122.2579935611575;4134.834606615732;5153.896922061575;5459.208414779835;6266.87466250677;6275.874482510324;6292.370762923717;3757.7745335279424;3769.9246015079543;4085.8774236772942;4176.290030694729;4176.644985176234;4217.40956266123;4246.171996460661;4266.592024631864;4286.678499112579;4290.935635965463;4266.274733295285;4447.599716025573;4774.9522504775205;4806.086843499794;4939.87650308738;5319.526420214619;5954.761809527657;5964.671943043174;5981.0729336952845;6019.2175017248355;1287.4077924154806;1415.7663985442287;1453.9200343980972;1454.2219912346843;1461.335092532895;815.7430409421013;875.8073223890734;895.7939673874915;912.2018234045211;955.8709574207393;4970.52779985908;5007.973365460072;5247.350088205508;5493.752781124859;5552.222688823568;4927.827526036572;5143.3570803649545;5422.7017514036315;6090.36051214625;6118.388161183935;4477;4913.926291105637;5299.304222648715;5461.726913654302;5524.972375138179;3087.799293045945;3181.7931834430688;3188.537662038991;3211.2614098757417;3211.715313874469;5739.540836733051;6046.697665116814;6296.024116261987;6304.936950630527;6314.463270622048;5290.735463226823;5448.37343705471;5971.4924231439945;6124.908126378108;6250.968745156336;5199.9460213216325;6290.591111577733;6307.40477927586;6345.619262844152;6370.74517019314;5763.4812866842185;6098.207233059674;6250.749490346426;6256.8101164593845;6310.211223597051;5220.451852555427;5289.889123284153;5827.213326200908;6153.323134455117;6171.506279497719;5676.282035266907;5717.742701578522;5791.102379131242;6460.935390646221;6496.317886622026;3621.0766254604905;4058.159288498004;4073.6537394321813;4078.490188726409;4080.1227736036894;6292.622442653455;6293.576972865389;6302.842428939313;6337;6342.524310676626;5115.565176959852;5523.226748255167;5970.641761494324;6076.57463977535;6118.969405152946;5547.0030788916765;5706.9714651426475;5950.7917222896995;6009.128676341993;6038.486728628116;5439.864003399946;5644.463775941279;5922.726613777886;5957.821265362046;5970.343262241063;5387.919181212364;5869.4717475427415;5933.228680271537;6031.52707389968;6086;5076.263941728503;5433.728313584226;5969.432903874299;5991.310999234969;5993.820185394445 05:50:39 INFO - PROCESS | 2566 | |5;yui.html;655.8688262347532;681.1213534540443;681.8056853796668;725.3218240944717;749.8350362920157;14910.627234319147;15301.92349038256;15695.45065922693;15771.053736775788;15827.446039388624;13915.652108697288;14223.21772302523;14281.143131412122;14283.642908927282;14289.285535723224;725.9709611615556;892.8660700894869;901.4366021236727;917.101240784032;921.7832461151249;896.8161526887005;897.7306807957619;899.0470101692227;914.2959920860933;924.9028851970547;951.4101257220533;960.9327347085707;968.5980318167951;970.3498655900528;971.9076687714655;942.0532364973193;950.4297421547017;959.3524371049514;961.024560071528;962.2350231565922;589.7965202005302;697.8918267668469;715.5885365914568;718.4467959671024;721.4228617106294;14523.564293071226;14558;14829.110253384724;15066.472673456487;15096;12655.303958282291;12678.429470673842;12687.936560317232;12700.364981750969;12995.545155919592;7932;8325;10142.442165680885;10203.89796102037;10428.634997775118;2642.5507663697217;3480.582330120402;3484.6863782259466;3491.3715531204375;3504.6495350465007;87973.12026879714;89333;89534.10465895323;90608;91852;8118.147594502492;9234.353595248402;9329.253659707207;9459.19596834263;9659.71020869375;20457.79542204559;21126;21145.78854211469;21725.348239552837;22481.775182248297;7605.961970190225;7775.028121484815;7779.222077792176;7780.416468764865;7791.454598178187;4140.68944829139;4200.024940765681;4205.726627769185;4249.383839343278;4426.402435671208;3649.3248748981523;3684.9631503685155;3726.9440958385644;3728.105254738843;3731.7947512887054;646.9870602587922;648.1573953859963;649.6037417175613;651.3421444341154;663.2074670768385;503.45626723138923;518.7561845932406;566.91496275559;585.73934599103;586.2818047891384;7807.375409967189;7919.841603167904;8080.191980801873;8118.2693557580105;8125.79685507867;520.4197319988186;562.3448682285092;565.8783361577281;600.9038553831366;603.3544107804695;3677.650623190846;3828.2343531293295;3859.845606175778;3868.8065596720053;3880.5731369549326;100949;102783.48608256911;103726.48136759269;104965;105917.47041264898;86632;87787;90740;91352.62971055439;91674.54162729143;70812.99999999897;78154.65536033928;80341.59829200934;80742;80743.59628201822;7555.9244407555225;7598.5876970509835;7690.730824421118;7744.922550774646;7785.92214077852;41236;48565.7858553539;48794.26808597803;49331.506684934124;50731.98536029185;60065.69967150136;60200;61655.6917215429;65340.69318613696;70811.9378209317;10668.573257069631;11085.889141108808;11095.50070246857;11397.829032564685;11478.139139564733;12236.265824050586;12239.938800305941;12300.200486968319;12378.257304561756;12511.874881251435;9407.717768466957;9466.810663786548;11418.771624567627;11483.540658373571;11496.137789665641;9585.329026968324;10669.146468282364;11190.104791616488;11398.031167350537;11468.655940321803;9558;11261.605843795427;11449.885501144881;11473.82789258145;11662.300261984308;9697.127258546765;9797.559109840216;9882.357646752938;9919.15687166599;9948.104670579683;9522.571484283086;9798.951005244928;9902;9966.700998970042;9987.25095617817 05:50:39 INFO - PROCESS | 2566 | __end_tp_report 05:50:39 INFO - PROCESS | 2566 | __start_cc_report 05:50:39 INFO - PROCESS | 2566 | _x_x_mozilla_cycle_collect,426 05:50:39 INFO - PROCESS | 2566 | __end_cc_report 05:50:39 INFO - PROCESS | 2566 | __startTimestamp1463662239769__endTimestamp 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | ------- Summary: start ------- 05:50:39 INFO - PROCESS | 2566 | Number of tests: 6 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | [#0] dojo.html Cycles:165 Average:5845.42 Median:6144.68 stddev:696.11 (11.3%) stddev-sans-first:659.53 05:50:39 INFO - PROCESS | 2566 | Values: 2927.4 3468.4 3490.3 3499.1 3523.5 5868.7 5939.1 5994.2 6030.1 6057.6 5020.6 5906.7 5935.6 5967.9 6002.3 5263.7 5283.2 5338.5 5387.5 5431.0 5355.7 5442.1 5475.6 5500.7 5530.4 5186.8 5263.2 5285.5 5350.0 5374.3 4411.4 5236.8 5319.3 5334.3 5412.3 5308.6 5357.2 5385.0 5409.3 5423.8 5596.5 5673.4 5775.6 5776.9 5796.7 6274.5 6319.1 6320.8 6329.0 6332.7 6228.1 6257.7 6329.2 6347.7 6358.2 6021.1 6105.3 6107.6 6111.7 6120.6 6476.4 6486.4 6505.2 6511.1 6612.1 5402.9 6194.4 6275.0 6356.5 6362.5 6343.5 6357.1 6362.2 6375.6 6376.8 6117.2 6200.3 6232.1 6281.8 6313.1 6250.6 6255.7 6288.8 6317.7 6354.6 4289.5 5101.9 5131.5 5134.4 5151.7 4050.7 4070.9 4540.8 4590.4 4620.7 5827.4 5848.5 5878.5 6016.3 6042.0 6322.2 6333.1 6333.1 6343.4 6368.4 5870.7 5915.6 5931.2 5951.4 5957.3 4557.9 5453.5 5486.4 5541.5 5578.6 5513.0 6445.8 6450.2 6490.9 6512.8 5473.2 6311.8 6312.3 6319.1 6339.3 6348.8 6410.3 6442.0 6465.8 6559.1 6295.8 6295.9 6302.4 6333.4 6334.3 6168.9 6309.1 6387.7 6388.4 6392.6 6144.7 6194.0 6195.8 6246.7 6249.7 4600.5 6169.7 6192.6 6192.6 6298.2 6075.7 6158.7 6182.8 6237.1 6267.5 6137.5 6259.7 6271.3 6302.6 6350.2 6258.1 6266.6 6288.8 6290.6 6330.3 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | [#1] ext.html Cycles:180 Average:72232.77 Median:39176.71 stddev:163642.41 (417.7%) stddev-sans-first:164012.81 05:50:39 INFO - PROCESS | 2566 | Values: 487.5 505.1 512.2 540.6 560.7 39669.8 42683.8 42716.8 42728.4 42851.0 42651.6 43224.1 43227.4 43450.8 43613.3 9690.0 9887.8 9891.5 9910.3 9948.7 9734.5 9809.7 9822.1 9833.0 9863.6 8836.9 9821.0 9840.8 9860.6 9879.3 8373.4 9768.7 9826.3 9828.5 9857.2 9669.4 9753.1 9756.9 9800.1 9800.7 40042.6 40177.8 40476.2 40601.8 40783.4 2713.7 2830.7 3070.6 3213.4 3590.6 24449.6 27592.0 28097.0 28262.4 28527.7 2656.8 2669.2 2699.4 2769.9 2914.0 540688.0 542275.0 545877.3 546579.0 549318.0 35704.0 36566.8 36620.6 65532.3 66083.0 37222.1 37573.1 37639.4 37657.8 37904.4 13848.8 15183.5 15368.6 15661.5 15680.8 13046.1 13122.6 13209.3 13232.6 13249.6 8208.0 8253.5 8292.7 8314.2 8315.6 2970.0 3222.0 3415.9 3442.4 3479.6 845.4 923.4 1026.7 1034.5 1040.3 26176.7 28077.0 29812.8 29834.9 30358.0 1093.9 1130.8 1169.3 1192.3 1277.3 13404.6 15402.3 15721.8 15803.0 15872.5 669513.0 689681.0 691608.0 694655.0 695210.0 64105.0 64649.0 64824.7 64832.0 65052.0 1445.1 1481.6 1498.9 1503.3 1510.0 29220.6 29342.4 29411.9 29884.3 30070.2 53530.0 53970.5 54258.5 54341.2 54594.5 506628.0 596621.0 608408.0 612140.0 612795.0 26897.0 27130.7 27519.4 27849.0 27897.9 46982.8 47571.0 47750.0 47845.0 49386.5 37362.8 38224.8 38557.4 39185.8 39487.4 38204.4 38375.6 38489.8 38673.2 39176.8 37931.8 38735.0 39176.6 39186.8 39312.0 32535.8 33902.7 34437.5 34729.7 34775.1 21970.9 24468.4 24774.0 26003.9 26024.6 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | [#2] jquery.html Cycles:180 Average:34208.93 Median:26203.04 stddev:99137.04 (378.3%) stddev-sans-first:99382.63 05:50:39 INFO - PROCESS | 2566 | Values: 394.0 395.1 397.0 398.7 415.8 20187.1 21273.9 21277.9 21421.4 21910.8 20558.4 21201.0 21274.3 21640.8 21770.0 5912.6 6071.8 6148.2 6158.4 6178.5 5364.6 6009.7 6026.6 6076.1 6095.8 5811.0 5990.6 6012.4 6016.5 6038.9 5626.6 5884.9 6006.8 6034.5 6045.5 4869.7 5269.7 5545.6 5824.3 5867.3 18549.4 25223.0 25927.0 26052.2 26353.9 1810.0 1943.6 2133.4 2217.5 2436.6 9940.2 9949.3 10001.9 10454.8 10580.7 1526.4 1541.7 1657.9 1777.8 1877.3 79756.6 80388.4 111578.0 126569.0 126690.0 11676.1 11777.2 11788.1 11892.2 11990.6 15053.1 15075.1 15089.5 15101.9 15135.3 8680.5 8844.3 8899.2 8907.4 8926.1 8709.2 8735.5 8744.7 8804.5 8979.4 4715.7 4735.4 4739.2 4756.5 4794.5 1776.6 1922.2 2040.8 2045.7 2050.0 781.5 862.2 919.5 923.6 943.1 17581.8 20307.0 20535.0 20621.8 21425.8 883.4 914.2 918.7 953.5 961.0 11045.7 11329.4 11373.4 11390.8 11400.1 584541.1 589454.0 600668.0 602738.0 604321.0 78056.6 123486.4 128515.0 129669.0 134113.0 970.8 971.5 1000.4 1000.8 1004.0 18857.7 18931.7 19008.9 19079.8 19105.2 42988.8 46194.8 52530.7 52603.5 53198.7 73524.6 76496.6 77374.6 77556.0 77755.0 7926.5 7941.4 8047.1 8116.3 8336.2 10152.0 10596.8 12496.9 12576.2 12584.4 10250.4 10530.2 10619.7 10829.4 10944.7 10337.5 10393.3 10524.6 10619.7 11003.2 10718.7 10972.8 10978.7 11199.0 11239.6 6727.2 8006.7 8149.9 8157.8 8298.8 9509.0 10047.5 10553.0 10819.6 10889.6 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | [#3] mootools.html Cycles:180 Average:5986.57 Median:476.74 stddev:3391.37 (711.4%) stddev-sans-first:3384.87 05:50:39 INFO - PROCESS | 2566 | Values: 1600.1 1633.0 1871.4 1928.0 1974.6 8348.2 8885.4 8928.8 9426.2 9464.2 9081.1 9092.9 9104.5 9218.7 9259.2 2213.7 2523.6 2526.6 2526.6 2530.0 2478.5 2534.6 2535.8 2548.1 2552.0 2502.0 2504.9 2516.6 2534.8 2542.1 2468.0 2474.0 2487.4 2493.8 2495.2 2454.5 2474.4 2485.2 2487.3 2495.5 7856.3 8304.9 8846.6 9177.0 9233.5 5491.2 5633.2 5645.5 5681.2 5696.3 3491.4 3571.2 3736.0 3742.5 3761.8 1049.9 1058.4 1062.7 1064.5 1065.6 12296.3 12448.2 12454.3 12459.7 12482.6 4256.6 4980.4 5016.1 5122.7 5124.2 8879.2 10558.1 10558.7 10571.6 10599.9 5579.4 5630.7 5683.2 5693.8 5696.7 4439.8 4640.6 4957.8 5134.7 5179.1 4219.5 4223.8 4231.4 4254.3 4314.8 1376.2 1386.0 1390.4 1392.2 1407.8 759.7 912.5 930.9 947.4 972.8 6037.0 6205.7 6307.3 6329.8 6331.8 455.0 458.3 472.1 481.4 481.4 4093.7 4167.2 4221.2 4222.5 4273.3 10954.2 11347.1 11478.5 11507.6 11508.4 11359.8 11364.8 11388.8 11426.6 11480.0 7976.8 8469.5 8471.0 8608.2 8613.2 6058.1 6155.0 6246.3 6318.8 6373.2 10196.6 10303.5 10585.0 10637.3 10666.3 10814.7 10866.1 10887.5 10918.8 10949.3 7255.8 7595.8 7853.6 8023.7 8105.2 8769.3 8795.0 8806.9 8913.9 8916.4 7744.1 7858.9 7875.1 7946.8 7948.8 7653.7 7673.0 7711.7 7837.3 7842.6 7239.4 7298.3 7318.9 7360.9 7368.2 4571.2 4947.9 5086.2 5113.8 5172.3 5089.8 5180.9 5203.7 5239.8 5259.4 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | [#4] prototype.html Cycles:180 Average:4774.80 Median:5377.87 stddev:1501.82 (27.9%) stddev-sans-first:1468.36 05:50:39 INFO - PROCESS | 2566 | Values: 321.2 322.2 328.9 332.4 332.7 4642.4 5109.2 5459.3 5738.0 5805.0 5169.6 5510.4 5624.5 5714.0 5723.6 3744.8 4177.3 4230.7 4247.7 4252.2 4182.4 4229.0 4251.5 4253.8 4304.3 3987.8 4058.7 4182.8 4230.2 4230.9 3657.0 3693.3 3746.9 3921.8 4036.2 2782.3 2807.9 2917.5 3434.5 3628.3 4917.6 5375.2 5380.6 5404.6 5430.0 4141.9 4146.8 4796.7 4846.4 4968.6 5950.8 5972.6 5974.0 5990.7 5994.7 4093.3 4101.7 4108.4 4122.3 4134.8 5153.9 5459.2 6266.9 6275.9 6292.4 3757.8 3769.9 4085.9 4176.3 4176.6 4217.4 4246.2 4266.6 4286.7 4290.9 4266.3 4447.6 4775.0 4806.1 4939.9 5319.5 5954.8 5964.7 5981.1 6019.2 1287.4 1415.8 1453.9 1454.2 1461.3 815.7 875.8 895.8 912.2 955.9 4970.5 5008.0 5247.4 5493.8 5552.2 4927.8 5143.4 5422.7 6090.4 6118.4 4477.0 4913.9 5299.3 5461.7 5525.0 3087.8 3181.8 3188.5 3211.3 3211.7 5739.5 6046.7 6296.0 6304.9 6314.5 5290.7 5448.4 5971.5 6124.9 6251.0 5199.9 6290.6 6307.4 6345.6 6370.7 5763.5 6098.2 6250.7 6256.8 6310.2 5220.5 5289.9 5827.2 6153.3 6171.5 5676.3 5717.7 5791.1 6460.9 6496.3 3621.1 4058.2 4073.7 4078.5 4080.1 6292.6 6293.6 6302.8 6337.0 6342.5 5115.6 5523.2 5970.6 6076.6 6119.0 5547.0 5707.0 5950.8 6009.1 6038.5 5439.9 5644.5 5922.7 5957.8 5970.3 5387.9 5869.5 5933.2 6031.5 6086.0 5076.3 5433.7 5969.4 5991.3 5993.8 05:50:39 INFO - PROCESS | 2566 | 05:50:39 INFO - PROCESS | 2566 | [#5] yui.html Cycles:180 Average:19195.24 Median:30401.68 stddev:28267.25 (93.0%) stddev-sans-first:28312.27 05:50:39 INFO - PROCESS | 2566 | Values: 655.9 681.1 681.8 725.3 749.8 14910.6 15301.9 15695.5 15771.1 15827.4 13915.7 14223.2 14281.1 14283.6 14289.3 726.0 892.9 901.4 917.1 921.8 896.8 897.7 899.0 914.3 924.9 951.4 960.9 968.6 970.3 971.9 942.1 950.4 959.4 961.0 962.2 589.8 697.9 715.6 718.4 721.4 14523.6 14558.0 14829.1 15066.5 15096.0 12655.3 12678.4 12687.9 12700.4 12995.5 7932.0 8325.0 10142.4 10203.9 10428.6 2642.6 3480.6 3484.7 3491.4 3504.6 87973.1 89333.0 89534.1 90608.0 91852.0 8118.1 9234.4 9329.3 9459.2 9659.7 20457.8 21126.0 21145.8 21725.3 22481.8 7606.0 7775.0 7779.2 7780.4 7791.5 4140.7 4200.0 4205.7 4249.4 4426.4 3649.3 3685.0 3726.9 3728.1 3731.8 647.0 648.2 649.6 651.3 663.2 503.5 518.8 566.9 585.7 586.3 7807.4 7919.8 8080.2 8118.3 8125.8 520.4 562.3 565.9 600.9 603.4 3677.7 3828.2 3859.8 3868.8 3880.6 100949.0 102783.5 103726.5 104965.0 105917.5 86632.0 87787.0 90740.0 91352.6 91674.5 70813.0 78154.7 80341.6 80742.0 80743.6 7555.9 7598.6 7690.7 7744.9 7785.9 41236.0 48565.8 48794.3 49331.5 50732.0 60065.7 60200.0 61655.7 65340.7 70811.9 10668.6 11085.9 11095.5 11397.8 11478.1 12236.3 12239.9 12300.2 12378.3 12511.9 9407.7 9466.8 11418.8 11483.5 11496.1 9585.3 10669.1 11190.1 11398.0 11468.7 9558.0 11261.6 11449.9 11473.8 11662.3 9697.1 9797.6 9882.4 9919.2 9948.1 9522.6 9799.0 9902.0 9966.7 9987.3 05:50:39 INFO - PROCESS | 2566 | -------- Summary: end -------- 05:50:39 INFO - PROCESS | 2566 | 05:50:40 INFO - TEST-INFO | 2566: exit 0 05:50:40 INFO - TEST-OK | dromaeo_css | took 1112761ms 05:50:40 INFO - TEST-START | kraken 05:50:40 INFO - operating with platform_type : mac_ 05:50:40 INFO - Initialising browser for kraken test... 05:50:40 INFO - TEST-INFO | started process 2570 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpvn1S8I/profile http://localhost:58480/getInfo.html) 05:50:43 INFO - PROCESS | 2570 | __metrics Screen width/height:1600/1200 05:50:43 INFO - PROCESS | 2570 | colorDepth:24 05:50:43 INFO - PROCESS | 2570 | Browser inner width/height: 1024/689 05:50:43 INFO - PROCESS | 2570 | __metrics 05:50:43 ERROR - PROCESS | 2570 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:50:43 INFO - TEST-INFO | 2570: exit 0 05:50:43 INFO - Browser initialized. 05:50:43 INFO - Running cycle 1/1 for kraken test... 05:50:43 INFO - TEST-INFO | started process 2573 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpvn1S8I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:55 INFO - PROCESS | 2573 | RSS: Main: 249720832 05:50:55 INFO - PROCESS | 2573 | 05:50:58 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58480/tests/kraken/driver.html?testName=audio-beat-detection) 05:50:59 INFO - PROCESS | 2573 | RSS: Main: 261382144 05:50:59 INFO - PROCESS | 2573 | 05:51:02 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58480/tests/kraken/driver.html?testName=audio-dft) 05:51:02 INFO - PROCESS | 2573 | RSS: Main: 266117120 05:51:02 INFO - PROCESS | 2573 | 05:51:06 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58480/tests/kraken/driver.html?testName=audio-fft) 05:51:07 INFO - PROCESS | 2573 | RSS: Main: 266452992 05:51:07 INFO - PROCESS | 2573 | 05:51:09 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58480/tests/kraken/driver.html?testName=audio-oscillator) 05:51:10 INFO - PROCESS | 2573 | RSS: Main: 267206656 05:51:10 INFO - PROCESS | 2573 | 05:51:12 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58480/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:51:12 INFO - PROCESS | 2573 | RSS: Main: 267747328 05:51:12 INFO - PROCESS | 2573 | 05:51:15 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58480/tests/kraken/driver.html?testName=imaging-darkroom) 05:51:16 INFO - PROCESS | 2573 | RSS: Main: 268226560 05:51:16 INFO - PROCESS | 2573 | 05:51:19 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58480/tests/kraken/driver.html?testName=imaging-desaturate) 05:51:20 INFO - PROCESS | 2573 | RSS: Main: 268525568 05:51:20 INFO - PROCESS | 2573 | 05:51:23 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58480/tests/kraken/driver.html?testName=json-parse-financial) 05:51:24 INFO - PROCESS | 2573 | RSS: Main: 268541952 05:51:24 INFO - PROCESS | 2573 | 05:51:25 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58480/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:51:26 INFO - PROCESS | 2573 | RSS: Main: 268029952 05:51:26 INFO - PROCESS | 2573 | 05:51:28 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:29 INFO - PROCESS | 2573 | RSS: Main: 268001280 05:51:29 INFO - PROCESS | 2573 | 05:51:31 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:32 INFO - PROCESS | 2573 | RSS: Main: 268500992 05:51:32 INFO - PROCESS | 2573 | 05:51:35 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:35 INFO - PROCESS | 2573 | RSS: Main: 269692928 05:51:35 INFO - PROCESS | 2573 | 05:51:38 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:39 INFO - PROCESS | 2573 | RSS: Main: 270233600 05:51:39 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | Cycle 1(1): loaded http://localhost:58480/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58480/tests/kraken/driver.html?testName=ai-astar) 05:51:41 INFO - PROCESS | 2573 | RSS: Main: 272691200 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | __start_tp_report 05:51:41 INFO - PROCESS | 2573 | _x_x_mozilla_page_load 05:51:41 INFO - PROCESS | 2573 | _x_x_mozilla_page_load_details 05:51:41 INFO - PROCESS | 2573 | |i|pagename|runs| 05:51:41 INFO - PROCESS | 2573 | |0;ai-astar;112;117;105;112;114;113;109;112;116;115 05:51:41 INFO - PROCESS | 2573 | |1;audio-beat-detection;155;129;171;242;120;147;128;143;202;145 05:51:41 INFO - PROCESS | 2573 | |2;audio-dft;194;202;213;249;187;287;278;197;281;280 05:51:41 INFO - PROCESS | 2573 | |3;audio-fft;68;105;68;183;85;80;90;73;135;58 05:51:41 INFO - PROCESS | 2573 | |4;audio-oscillator;77;86;154;78;82;81;124;82;81;123 05:51:41 INFO - PROCESS | 2573 | |5;imaging-gaussian-blur;88;85;88;105;87;85;88;86;89;85 05:51:41 INFO - PROCESS | 2573 | |6;imaging-darkroom;147;139;95;98;93;95;119;112;98;149 05:51:41 INFO - PROCESS | 2573 | |7;imaging-desaturate;89;92;91;93;112;116;93;90;90;94 05:51:41 INFO - PROCESS | 2573 | |8;json-parse-financial;56;59;60;59;57;60;56;122;48;59 05:51:41 INFO - PROCESS | 2573 | |9;json-stringify-tinderbox;55;87;63;67;100;105;100;89;62;92 05:51:41 INFO - PROCESS | 2573 | |10;stanford-crypto-aes;83;86;87;87;95;113;108;83;86;87 05:51:41 INFO - PROCESS | 2573 | |11;stanford-crypto-ccm;134;138;136;144;130;132;133;151;255;156 05:51:41 INFO - PROCESS | 2573 | |12;stanford-crypto-pbkdf2;165;163;162;166;164;216;171;183;174;164 05:51:41 INFO - PROCESS | 2573 | |13;stanford-crypto-sha256-iterative;63;70;82;83;78;70;65;85;79;84 05:51:41 INFO - PROCESS | 2573 | __end_tp_report 05:51:41 INFO - PROCESS | 2573 | __start_cc_report 05:51:41 INFO - PROCESS | 2573 | _x_x_mozilla_cycle_collect,1081 05:51:41 INFO - PROCESS | 2573 | __end_cc_report 05:51:41 INFO - PROCESS | 2573 | __startTimestamp1463662301317__endTimestamp 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | ------- Summary: start ------- 05:51:41 INFO - PROCESS | 2573 | Number of tests: 14 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:3.50 (3.1%) stddev-sans-first:3.71 05:51:41 INFO - PROCESS | 2573 | Values: 112.0 117.0 105.0 112.0 114.0 113.0 109.0 112.0 116.0 115.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#1] audio-beat-detection Cycles:10 Average:158.20 Median:151.00 stddev:37.81 (25.0%) stddev-sans-first:40.09 05:51:41 INFO - PROCESS | 2573 | Values: 155.0 129.0 171.0 242.0 120.0 147.0 128.0 143.0 202.0 145.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#2] audio-dft Cycles:10 Average:236.80 Median:263.50 stddev:41.98 (15.9%) stddev-sans-first:41.57 05:51:41 INFO - PROCESS | 2573 | Values: 194.0 202.0 213.0 249.0 187.0 287.0 278.0 197.0 281.0 280.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#3] audio-fft Cycles:10 Average:94.50 Median:70.50 stddev:38.16 (54.1%) stddev-sans-first:39.25 05:51:41 INFO - PROCESS | 2573 | Values: 68.0 105.0 68.0 183.0 85.0 80.0 90.0 73.0 135.0 58.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:81.00 stddev:26.87 (33.2%) stddev-sans-first:27.53 05:51:41 INFO - PROCESS | 2573 | Values: 77.0 86.0 154.0 78.0 82.0 81.0 124.0 82.0 81.0 123.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:87.50 stddev:5.95 (6.8%) stddev-sans-first:6.30 05:51:41 INFO - PROCESS | 2573 | Values: 88.0 85.0 88.0 105.0 87.0 85.0 88.0 86.0 89.0 85.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#6] imaging-darkroom Cycles:10 Average:114.50 Median:94.00 stddev:22.71 (24.2%) stddev-sans-first:20.82 05:51:41 INFO - PROCESS | 2573 | Values: 147.0 139.0 95.0 98.0 93.0 95.0 119.0 112.0 98.0 149.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:91.50 stddev:9.66 (10.6%) stddev-sans-first:9.91 05:51:41 INFO - PROCESS | 2573 | Values: 89.0 92.0 91.0 93.0 112.0 116.0 93.0 90.0 90.0 94.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:59.00 stddev:20.82 (35.3%) stddev-sans-first:21.90 05:51:41 INFO - PROCESS | 2573 | Values: 56.0 59.0 60.0 59.0 57.0 60.0 56.0 122.0 48.0 59.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:65.00 stddev:18.46 (28.4%) stddev-sans-first:16.79 05:51:41 INFO - PROCESS | 2573 | Values: 55.0 87.0 63.0 67.0 100.0 105.0 100.0 89.0 62.0 92.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:86.50 stddev:10.61 (12.3%) stddev-sans-first:10.79 05:51:41 INFO - PROCESS | 2573 | Values: 83.0 86.0 87.0 87.0 95.0 113.0 108.0 83.0 86.0 87.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:141.00 stddev:37.56 (26.6%) stddev-sans-first:39.34 05:51:41 INFO - PROCESS | 2573 | Values: 134.0 138.0 136.0 144.0 130.0 132.0 133.0 151.0 255.0 156.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:168.50 stddev:16.50 (9.8%) stddev-sans-first:17.26 05:51:41 INFO - PROCESS | 2573 | Values: 165.0 163.0 162.0 166.0 164.0 216.0 171.0 183.0 174.0 164.0 05:51:41 INFO - PROCESS | 2573 | 05:51:41 INFO - PROCESS | 2573 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:80.50 stddev:8.20 (10.2%) stddev-sans-first:7.25 05:51:41 INFO - PROCESS | 2573 | Values: 63.0 70.0 82.0 83.0 78.0 70.0 65.0 85.0 79.0 84.0 05:51:41 INFO - PROCESS | 2573 | -------- Summary: end -------- 05:51:41 INFO - PROCESS | 2573 | 05:51:42 INFO - TEST-INFO | 2573: exit 0 05:51:42 INFO - TEST-OK | kraken | took 61782ms 05:51:42 INFO - SUITE-END | took 1174s 05:51:42 INFO - Completed test suite (00:19:34) 05:51:42 INFO - javascript benchmark 05:51:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7252.4369426445755, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2927.3706153177063, 3468.4450487921936, 3490.2670439207764, 3499.1077275294797, 3523.5067090607317, 5868.677222752753, 5939.0794426863795, 5994.220751302325, 6030.125631783389, 6057.606255593389, 5020.6234532410035, 5906.675132867691, 5935.614185077978, 5967.88064238716, 6002.339742628318, 5263.657862238952, 5283.207518872162, 5338.519533242007, 5387.542058924996, 5430.972845135769, 5355.705436201008, 5442.074847275952, 5475.561955043607, 5500.724963751816, 5530.364008139075, 5186.844394668147, 5263.184206448007, 5285.471452854722, 5349.973250133763, 5374.328208973879, 4411.382406463098, 5236.7905283788605, 5319.308489896327, 5334.27987221724, 5412.296401467824, 5308.575313974873, 5357.223202635635, 5385.0037743017665, 5409.26974858393, 5423.755930983076, 5596.468335508121, 5673.375928647887, 5775.5668324875805, 5776.9422305776825, 5796.739146738407, 6274.466670332981, 6319.115323854664, 6320.778772742978, 6328.968355158195, 6332.715027823759, 6228.065790131472, 6257.655828929407, 6329.208848893888, 6347.746090156388, 6358.237011558596, 6021.066734656135, 6105.269434892363, 6107.643995706967, 6111.682900513584, 6120.629100637178, 6476.449501792306, 6486.4486518646445, 6505.219373675094, 6511.0558968949235, 6612.07430959666, 5402.91895621566, 6194.4115309045565, 6275.0, 6356.523260755369, 6362.459190968727, 6343.524235682342, 6357.078223657544, 6362.236531616236, 6375.585586936834, 6376.808695739135, 6117.15760190703, 6200.3179650238435, 6232.127502149703, 6281.842953926189, 6313.084602732578, 6250.562460627803, 6255.718492667841, 6288.8427789304715, 6317.747290108344, 6354.586951848115, 4289.463817022872, 5101.8724531887, 5131.4868513148385, 5134.383873935077, 5151.665141765773, 4050.6556942659613, 4070.9415551956113, 4540.750258735735, 4590.449146102489, 4620.745858977789, 5827.35899051104, 5848.5321174305955, 5878.500327472213, 6016.338202797689, 6042.033274676119, 6322.209723784527, 6333.050042493571, 6333.113364129025, 6343.365663433619, 6368.363163683594, 5870.73581688825, 5915.645061296336, 5931.169636250928, 5951.4048595141, 5957.285125784847, 4557.886052848706, 5453.454654534515, 5486.423925487846, 5541.5289700375915, 5578.581606379537, 5513.0, 6445.838854028687, 6450.193725784277, 6490.870182596322, 6512.804615861532, 5473.2063850742215, 6311.849152778658, 6312.337204593451, 6319.05214217871, 6339.270983836917, 6348.77779277723, 6410.294867564471, 6442.0, 6465.838354041187, 6559.0817285579105, 6295.811125666238, 6295.874082518416, 6302.369763023661, 6333.398327158912, 6334.33489483616, 6168.938310616836, 6309.148264984352, 6387.68061596909, 6388.425041746265, 6392.584482008655, 6144.678894037804, 6193.969030155001, 6195.783147589813, 6246.656433896179, 6249.7187626557825, 4600.539946005507, 6169.6915154241215, 6192.566520343533, 6192.628442293477, 6298.244210694746, 6075.696215189135, 6158.692065396802, 6182.845428864314, 6237.126802247598, 6267.46726528232, 6137.5302083125, 6259.749610015549, 6271.31015588294, 6302.590331628429, 6350.174477317919, 6258.123862659322, 6266.561340706289, 6288.779892703734, 6290.559660823881, 6330.335314791881], "value": 5800.633849106559, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.54414622328125, 505.10596244646973, 512.2060805751084, 540.5729473715763, 560.694421540261, 39669.80165099174, 42683.78658106709, 42716.78641606795, 42728.35907461383, 42851.0, 42651.57348426515, 43224.13551728964, 43227.35158972618, 43450.782746086305, 43613.345799813025, 9689.982551832045, 9887.752806179831, 9891.455969921652, 9910.306278560467, 9948.6517971871, 9734.464604446752, 9809.65666201683, 9822.066903644143, 9832.950835245814, 9863.556139973718, 8836.890845756721, 9820.950895245514, 9840.753981150458, 9860.556274967608, 9879.308448408614, 8373.371997100241, 9768.706938791847, 9826.312158148932, 9828.508574571279, 9857.211423086135, 9669.371490853142, 9753.122219000254, 9756.853647195227, 9800.11798938099, 9800.705978820646, 40042.59957400447, 40177.794666160065, 40476.190476190604, 40601.79699101485, 40783.388249176285, 2713.7150599187, 2830.660320761501, 3070.5742463453844, 3213.373392188524, 3590.605033446319, 24449.633255501183, 27592.034278800347, 28097.01660441895, 28262.434751305063, 28527.714722852714, 2656.7874570034546, 2669.159214847317, 2699.4061306512535, 2769.903053393142, 2913.980106962546, 540688.0, 542275.0, 545877.2706136483, 546578.999999996, 549318.0, 35704.0, 36566.817165914, 36620.63379366226, 65532.344676552624, 66083.0, 37222.06944826347, 37573.06067348338, 37639.4354084689, 37657.81171094127, 37904.431433528516, 13848.792268115987, 15183.468578599695, 15368.615784605474, 15661.451849185223, 15680.843191568167, 13046.086773925925, 13122.60632181036, 13209.273489958145, 13232.603021909357, 13249.602511924657, 8207.95896020528, 8253.463524870864, 8292.709755158661, 8314.168583141638, 8315.625796839156, 2969.9703002970127, 3221.9677803222135, 3415.8898358033243, 3442.4492081266876, 3479.565054368204, 845.4166625028711, 923.3951761596165, 1026.7279171019686, 1034.5034383495813, 1040.2718097331897, 26176.73823261743, 28077.0, 29812.807487700684, 29834.850825745732, 30358.0, 1093.9234253602172, 1130.8360287758394, 1169.2984209474419, 1192.3382522699985, 1277.348552238365, 13404.597862064154, 15402.306896189699, 15721.842781572266, 15803.0, 15872.52382428529, 669513.0, 689681.0, 691608.0, 694655.0, 695210.0, 64105.03842442367, 64649.03026454607, 64824.67587662031, 64832.027519587245, 65052.0, 1445.1112565772185, 1481.585156156278, 1498.8501149884924, 1503.3385310463361, 1510.0, 29220.56169157507, 29342.413151737273, 29411.85294073516, 29884.252893677833, 30070.24824379408, 53530.0, 53970.460295396544, 54258.45741542534, 54341.184882227586, 54594.45405545894, 506628.0, 596621.0, 608408.0, 612140.0, 612795.0, 26897.0, 27130.72869271282, 27519.44961100727, 27849.02528411496, 27897.860510697315, 46982.76508617435, 47571.04857902892, 47750.0, 47845.0, 49386.506134938194, 37362.8131859339, 38224.80887595656, 38557.42163867488, 39185.804070979466, 39487.407688884115, 38204.42693359546, 38375.61624383832, 38489.845304640905, 38673.226535469694, 39176.80411597924, 37931.810340949225, 38735.0, 39176.60823391729, 39186.804065979486, 39312.0, 32535.837320813243, 33902.660973390935, 34437.48343774895, 34729.65270347264, 34775.13062173466, 21970.89014554917, 24468.409553378973, 24774.0, 26003.869980649975, 26024.60963085593], "value": 18862.26500572109, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9980300098501, 395.1090291392909, 396.9801509924504, 398.70495833083527, 415.8295099009405, 20187.09158087886, 21273.893630531846, 21277.936103194857, 21421.357359279245, 21910.780892191073, 20558.38324850253, 21201.0, 21274.255401060967, 21640.783592164073, 21770.0, 5912.556558258127, 6071.757129714806, 6148.169997050386, 6158.353372895848, 6178.5366097542865, 5364.597655175858, 6009.669468179249, 6026.578139530235, 6076.088586712007, 6095.786647467339, 5811.0, 5990.6106103103375, 6012.3687012863675, 6016.518678505709, 6038.939610603904, 5626.578006649518, 5884.911726324109, 6006.7897623583185, 6034.487068599175, 6045.486133678643, 4869.659123861331, 5269.683818970874, 5545.584081193927, 5824.330202026778, 5867.266591676041, 18549.443516694522, 25223.0, 25927.0, 26052.218433447026, 26353.868230658725, 1809.972850407245, 1943.6112777444425, 2133.381319417367, 2217.4899773052125, 2436.610142377211, 9940.15508681763, 9949.30354875159, 10001.949990250076, 10454.843177352346, 10580.735481613021, 1526.3820350428582, 1541.6510553265891, 1657.9088150151738, 1777.8044415114327, 1877.305397003104, 79756.60121699412, 80388.43654291466, 111578.0, 126569.0, 126690.0, 11676.124290678063, 11777.234479758788, 11788.056955443544, 11892.227005244631, 11990.640280791591, 15053.096814191184, 15075.09549427038, 15089.47186848455, 15101.924490377478, 15135.318910649048, 8680.479171249744, 8844.292456603456, 8899.243564296978, 8907.421017633833, 8926.107389261022, 8709.172628600272, 8735.519546425008, 8744.737657870273, 8804.47173169599, 8979.371443998987, 4715.669903106818, 4735.431748190239, 4739.194336962724, 4756.476787553366, 4794.472608013116, 1776.5558610347414, 1922.1638587214607, 2040.755109386883, 2045.7136000959877, 2050.036482853057, 781.5427974634903, 862.2283056664251, 919.5126582911067, 923.6443969071955, 943.056943056943, 17581.824181758275, 20307.0, 20535.0, 20621.793782062286, 21425.785742142692, 883.3551507399634, 914.1726737302752, 918.6738707758783, 953.4755884263627, 960.9519524023771, 11045.668629941114, 11329.37688427128, 11373.431328433711, 11390.829137562943, 11400.144989125849, 584541.0772946193, 589454.0, 600668.0, 602738.0, 604321.0, 78056.60971695105, 123486.38256808837, 128515.0, 129669.0, 134113.0, 970.8446648536342, 971.4705485510414, 1000.4297550396204, 1000.8348622477209, 1003.9698809035887, 18857.71713424327, 18931.716024259924, 19008.904955475686, 19079.80920190836, 19105.235790568222, 42988.78505607452, 46194.76104778991, 52530.73734631302, 52603.473965261386, 53198.73400632972, 73524.63237683778, 76496.61751691205, 77374.61312693401, 77556.0, 77755.0, 7926.48477848938, 7941.364690824605, 8047.114817370201, 8116.310113640407, 8336.208060234268, 10152.0, 10596.788064238517, 12496.87503124957, 12576.245425274512, 12584.370781461073, 10250.436226007641, 10530.157587393143, 10619.734506637087, 10829.350238985686, 10944.671659850217, 10337.534810933677, 10393.324433911772, 10524.631637892944, 10619.734506637396, 11003.17476189305, 10718.678439646823, 10972.835407468734, 10978.725531861768, 11199.0, 11239.60661376848, 6727.226368967631, 8006.719764808204, 8149.877751833846, 8157.75526734199, 8298.767568857853, 9509.0, 10047.547860346156, 10553.0, 10819.621313253998, 10889.618863340062], "value": 9461.327708451192, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1600.1199340362798, 1632.9836701632985, 1871.4385684294716, 1928.0456174193775, 1974.5853370792133, 8348.206920342573, 8885.378023538355, 8928.821423571524, 9426.151646351818, 9464.242860571156, 9081.137291957253, 9092.909070909289, 9104.499252541107, 9218.723438296862, 9259.166674999266, 2213.7122174117344, 2523.621456781488, 2526.5704830178915, 2526.621006848978, 2529.987350063247, 2478.491909158618, 2534.619807028942, 2535.784458321036, 2548.120898290087, 2552.0174732727846, 2502.049221295905, 2504.9373765655823, 2516.6225066240117, 2534.822562420631, 2542.0594380079397, 2467.9753202467923, 2474.0, 2487.3781554611346, 2493.800495960319, 2495.214007587604, 2454.484558242771, 2474.381404648838, 2485.179890636086, 2487.315988103268, 2495.4759500504733, 7856.332211762007, 8304.916950830475, 8846.557672116369, 9176.99053104158, 9233.49215793131, 5491.203775452577, 5633.211350410946, 5645.491905728504, 5681.204631351614, 5696.3449203341315, 3491.423915054013, 3571.2143328467696, 3736.0, 3742.4573436851506, 3761.793101379423, 1049.8792638846553, 1058.4337379502006, 1062.713067471786, 1064.5050051725984, 1065.5737704918095, 12296.262224266484, 12448.190867593577, 12454.315012674298, 12459.688507787378, 12482.563110291187, 4256.59562341577, 4980.377452818398, 5016.122178618727, 5122.692638441706, 5124.180131178993, 8879.156480134377, 10558.102561282378, 10558.736031599117, 10571.62999295021, 10599.894001060045, 5579.38626751057, 5630.746616402272, 5683.204351390808, 5693.829185124453, 5696.658200507983, 4439.755813430293, 4640.582347588733, 4957.752112394438, 5134.743262836918, 5179.145441002268, 4219.472565929259, 4223.767692776927, 4231.449911511484, 4254.29804082323, 4314.7626880521875, 1376.2224343246035, 1386.031834794574, 1390.4368730664098, 1392.192528333564, 1407.7958695989025, 759.7416878261417, 912.5072460871188, 930.9394889332307, 947.3557980573139, 972.8443449048118, 6036.9698151508965, 6205.720742566596, 6307.337729538424, 6329.810105696836, 6331.778387756406, 454.98635040948824, 458.3102430841562, 472.1077164159741, 481.3670023918536, 481.4319103457953, 4093.672506199497, 4167.249895018865, 4221.197972385237, 4222.535521092739, 4273.273543497551, 10954.178436617285, 11347.148963827745, 11478.483468243949, 11507.619085709768, 11508.36703981289, 11359.829602555968, 11364.772704545863, 11388.829167562328, 11426.600068997712, 11480.0, 7976.760697179094, 8469.491830490191, 8470.957645211858, 8608.182222688834, 8613.224809767025, 6058.12157237207, 6155.0, 6246.344133865879, 6318.84202894913, 6373.171487706569, 10196.643117490852, 10303.484825758533, 10584.994425529563, 10637.308574942605, 10666.253362264568, 10814.729631759268, 10866.07638350749, 10887.455627218766, 10918.836217456903, 10949.288296260718, 7255.782326530212, 7595.810104747205, 7853.646585903763, 8023.719169829028, 8105.230003149692, 8769.254613357936, 8794.956025219833, 8806.911930880866, 8913.910860891308, 8916.420432672116, 7744.148143704076, 7858.882116768367, 7875.133735289228, 7946.801329966797, 7948.761537153895, 7653.655585498777, 7672.961635191788, 7711.652975615999, 7837.294643482114, 7842.62754018056, 7239.420846332176, 7298.306660867209, 7318.853622927618, 7360.852782944418, 7368.1526624438875, 4571.1543364477675, 4947.876303092451, 5086.237064440364, 5113.821016264413, 5172.301739265302, 5089.821856235014, 5180.922286165786, 5203.73981300941, 5239.764210610608, 5259.368875734936], "value": 4724.997470182964, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.1857940121791, 322.205762794711, 328.9013296011197, 332.43486073674757, 332.6523782647134, 4642.396488456505, 5109.233614957753, 5459.33227090837, 5737.971310143449, 5804.984127777632, 5169.588071872565, 5510.448955104487, 5624.465675760806, 5713.971485132674, 5723.597718558948, 3744.794036327994, 4177.268977928866, 4230.682698797587, 4247.723897946624, 4252.2345977724, 4182.372644103393, 4229.0, 4251.532331443539, 4253.766042867641, 4304.332828411601, 3987.7806720630356, 4058.736182148151, 4182.762222211118, 4230.2174097792, 4230.894227644303, 3657.0491672165163, 3693.2613477304376, 3746.850125994957, 3921.7843018634107, 4036.233115708006, 2782.2627003844, 2807.873645685949, 2917.4602698500635, 3434.4504879219203, 3628.2924829658227, 4917.606591472673, 5375.16684913839, 5380.569554435675, 5404.621676482648, 5429.995450841607, 4141.8964525886795, 4146.792660366969, 4796.666316909749, 4846.442659094214, 4968.621337490505, 5950.761969521214, 5972.611780234271, 5973.970130149365, 5990.700464976734, 5994.730237139339, 4093.3450647896193, 4101.651359634435, 4108.404281379213, 4122.2579935611575, 4134.834606615732, 5153.896922061575, 5459.208414779835, 6266.87466250677, 6275.874482510324, 6292.370762923717, 3757.7745335279424, 3769.9246015079543, 4085.8774236772942, 4176.290030694729, 4176.644985176234, 4217.40956266123, 4246.171996460661, 4266.592024631864, 4286.678499112579, 4290.935635965463, 4266.274733295285, 4447.599716025573, 4774.9522504775205, 4806.086843499794, 4939.87650308738, 5319.526420214619, 5954.761809527657, 5964.671943043174, 5981.0729336952845, 6019.2175017248355, 1287.4077924154806, 1415.7663985442287, 1453.9200343980972, 1454.2219912346843, 1461.335092532895, 815.7430409421013, 875.8073223890734, 895.7939673874915, 912.2018234045211, 955.8709574207393, 4970.52779985908, 5007.973365460072, 5247.350088205508, 5493.752781124859, 5552.222688823568, 4927.827526036572, 5143.3570803649545, 5422.7017514036315, 6090.36051214625, 6118.388161183935, 4477.0, 4913.926291105637, 5299.304222648715, 5461.726913654302, 5524.972375138179, 3087.799293045945, 3181.7931834430688, 3188.537662038991, 3211.2614098757417, 3211.715313874469, 5739.540836733051, 6046.697665116814, 6296.024116261987, 6304.936950630527, 6314.463270622048, 5290.735463226823, 5448.37343705471, 5971.4924231439945, 6124.908126378108, 6250.968745156336, 5199.9460213216325, 6290.591111577733, 6307.40477927586, 6345.619262844152, 6370.74517019314, 5763.4812866842185, 6098.207233059674, 6250.749490346426, 6256.8101164593845, 6310.211223597051, 5220.451852555427, 5289.889123284153, 5827.213326200908, 6153.323134455117, 6171.506279497719, 5676.282035266907, 5717.742701578522, 5791.102379131242, 6460.935390646221, 6496.317886622026, 3621.0766254604905, 4058.159288498004, 4073.6537394321813, 4078.490188726409, 4080.1227736036894, 6292.622442653455, 6293.576972865389, 6302.842428939313, 6337.0, 6342.524310676626, 5115.565176959852, 5523.226748255167, 5970.641761494324, 6076.57463977535, 6118.969405152946, 5547.0030788916765, 5706.9714651426475, 5950.7917222896995, 6009.128676341993, 6038.486728628116, 5439.864003399946, 5644.463775941279, 5922.726613777886, 5957.821265362046, 5970.343262241063, 5387.919181212364, 5869.4717475427415, 5933.228680271537, 6031.52707389968, 6086.0, 5076.263941728503, 5433.728313584226, 5969.432903874299, 5991.310999234969, 5993.820185394445], "value": 4296.173758116366, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.8688262347532, 681.1213534540443, 681.8056853796668, 725.3218240944717, 749.8350362920157, 14910.627234319147, 15301.92349038256, 15695.45065922693, 15771.053736775788, 15827.446039388624, 13915.652108697288, 14223.21772302523, 14281.143131412122, 14283.642908927282, 14289.285535723224, 725.9709611615556, 892.8660700894869, 901.4366021236727, 917.101240784032, 921.7832461151249, 896.8161526887005, 897.7306807957619, 899.0470101692227, 914.2959920860933, 924.9028851970547, 951.4101257220533, 960.9327347085707, 968.5980318167951, 970.3498655900528, 971.9076687714655, 942.0532364973193, 950.4297421547017, 959.3524371049514, 961.024560071528, 962.2350231565922, 589.7965202005302, 697.8918267668469, 715.5885365914568, 718.4467959671024, 721.4228617106294, 14523.564293071226, 14558.0, 14829.110253384724, 15066.472673456487, 15096.0, 12655.303958282291, 12678.429470673842, 12687.936560317232, 12700.364981750969, 12995.545155919592, 7932.0, 8325.0, 10142.442165680885, 10203.89796102037, 10428.634997775118, 2642.5507663697217, 3480.582330120402, 3484.6863782259466, 3491.3715531204375, 3504.6495350465007, 87973.12026879714, 89333.0, 89534.10465895323, 90608.0, 91852.0, 8118.147594502492, 9234.353595248402, 9329.253659707207, 9459.19596834263, 9659.71020869375, 20457.79542204559, 21126.0, 21145.78854211469, 21725.348239552837, 22481.775182248297, 7605.961970190225, 7775.028121484815, 7779.222077792176, 7780.416468764865, 7791.454598178187, 4140.68944829139, 4200.024940765681, 4205.726627769185, 4249.383839343278, 4426.402435671208, 3649.3248748981523, 3684.9631503685155, 3726.9440958385644, 3728.105254738843, 3731.7947512887054, 646.9870602587922, 648.1573953859963, 649.6037417175613, 651.3421444341154, 663.2074670768385, 503.45626723138923, 518.7561845932406, 566.91496275559, 585.73934599103, 586.2818047891384, 7807.375409967189, 7919.841603167904, 8080.191980801873, 8118.2693557580105, 8125.79685507867, 520.4197319988186, 562.3448682285092, 565.8783361577281, 600.9038553831366, 603.3544107804695, 3677.650623190846, 3828.2343531293295, 3859.845606175778, 3868.8065596720053, 3880.5731369549326, 100949.0, 102783.48608256911, 103726.48136759269, 104965.0, 105917.47041264898, 86632.0, 87787.0, 90740.0, 91352.62971055439, 91674.54162729143, 70812.99999999897, 78154.65536033928, 80341.59829200934, 80742.0, 80743.59628201822, 7555.9244407555225, 7598.5876970509835, 7690.730824421118, 7744.922550774646, 7785.92214077852, 41236.0, 48565.7858553539, 48794.26808597803, 49331.506684934124, 50731.98536029185, 60065.69967150136, 60200.0, 61655.6917215429, 65340.69318613696, 70811.9378209317, 10668.573257069631, 11085.889141108808, 11095.50070246857, 11397.829032564685, 11478.139139564733, 12236.265824050586, 12239.938800305941, 12300.200486968319, 12378.257304561756, 12511.874881251435, 9407.717768466957, 9466.810663786548, 11418.771624567627, 11483.540658373571, 11496.137789665641, 9585.329026968324, 10669.146468282364, 11190.104791616488, 11398.031167350537, 11468.655940321803, 9558.0, 11261.605843795427, 11449.885501144881, 11473.82789258145, 11662.300261984308, 9697.127258546765, 9797.559109840216, 9882.357646752938, 9919.15687166599, 9948.104670579683, 9522.571484283086, 9798.951005244928, 9902.0, 9966.700998970042, 9987.25095617817], "value": 6924.027020943786, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1634.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 105.0, 112.0, 114.0, 113.0, 109.0, 112.0, 116.0, 115.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 129.0, 171.0, 242.0, 120.0, 147.0, 128.0, 143.0, 202.0, 145.0], "value": 158.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 202.0, 213.0, 249.0, 187.0, 287.0, 278.0, 197.0, 281.0, 280.0], "value": 236.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 105.0, 68.0, 183.0, 85.0, 80.0, 90.0, 73.0, 135.0, 58.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 86.0, 154.0, 78.0, 82.0, 81.0, 124.0, 82.0, 81.0, 123.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 105.0, 87.0, 85.0, 88.0, 86.0, 89.0, 85.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 139.0, 95.0, 98.0, 93.0, 95.0, 119.0, 112.0, 98.0, 149.0], "value": 114.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 91.0, 93.0, 112.0, 116.0, 93.0, 90.0, 90.0, 94.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 60.0, 59.0, 57.0, 60.0, 56.0, 122.0, 48.0, 59.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 87.0, 63.0, 67.0, 100.0, 105.0, 100.0, 89.0, 62.0, 92.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 87.0, 87.0, 95.0, 113.0, 108.0, 83.0, 86.0, 87.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 136.0, 144.0, 130.0, 132.0, 133.0, 151.0, 255.0, 156.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 166.0, 164.0, 216.0, 171.0, 183.0, 174.0, 164.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 82.0, 83.0, 78.0, 70.0, 65.0, 85.0, 79.0, 84.0], "value": 75.9, "unit": "score"}]}]} 05:51:42 INFO - Return code: 0 05:51:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:51:42 ERROR - # TBPL SUCCESS # 05:51:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:42 INFO - 2016-05-19 05:51:42.629 screenresolution[2578:33227] starting screenresolution argv=screenresolution get 05:51:42 INFO - 2016-05-19 05:51:42.632 screenresolution[2578:33227] Display 0: 1600x1200x32@60 05:51:42 INFO - 2016-05-19 05:51:42.638 screenresolution[2579:33230] starting screenresolution argv=screenresolution list 05:51:42 INFO - Available Modes on Display 0 05:51:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:42 INFO - Intel Iris: 05:51:42 INFO - Chipset Model: Intel Iris 05:51:42 INFO - Type: GPU 05:51:42 INFO - Bus: Built-In 05:51:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:42 INFO - Vendor: Intel (0x8086) 05:51:42 INFO - Device ID: 0x0a2e 05:51:42 INFO - Revision ID: 0x0009 05:51:42 INFO - Displays: 05:51:42 INFO - Display: 05:51:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:42 INFO - Display Serial Number: 1600x1200 60 05:51:42 INFO - Main Display: Yes 05:51:42 INFO - Mirror: Off 05:51:42 INFO - Online: Yes 05:51:42 INFO - Rotation: Supported 05:51:42 INFO - Return code: 0 05:51:42 INFO - Running post-action listener: _resource_record_post_action 05:51:42 INFO - ##### 05:51:42 INFO - ##### Finished run-tests step (success) 05:51:42 INFO - ##### 05:51:42 INFO - Running post-run listener: _resource_record_post_run 05:51:42 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 253952; Write bytes: 698654208; Read time: 2; Write time: 1937 05:51:42 INFO - TinderboxPrint: CPU usage
54.5% 05:51:42 INFO - TinderboxPrint: I/O read bytes / time
253,952 / 2 05:51:42 INFO - TinderboxPrint: I/O write bytes / time
698,654,208 / 1,937 05:51:42 INFO - TinderboxPrint: CPU idle
2,201.7 (45.5%) 05:51:42 INFO - TinderboxPrint: CPU system
979.0 (20.2%) 05:51:42 INFO - TinderboxPrint: CPU user
1,661.0 (34.3%) 05:51:42 INFO - TinderboxPrint: Swap in / out
395,059,200 / 0 05:51:42 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396290560; Write bytes: 399173120; Read time: 29912; Write time: 808 05:51:42 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 253952; Write bytes: 298633216; Read time: 2; Write time: 1125 05:51:43 INFO - Running post-run listener: _upload_blobber_files 05:51:43 INFO - Blob upload gear active. 05:51:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:43 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 05:51:43 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'] 05:51:43 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 05:51:43 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:51:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:44 INFO - (blobuploader) - INFO - Done attempting. 05:51:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:51:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:51:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:46 INFO - (blobuploader) - INFO - Done attempting. 05:51:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:49 INFO - (blobuploader) - INFO - Done attempting. 05:51:49 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:49 INFO - Return code: 0 05:51:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11494c6098f26d7ee6f6be538579f8a456bca23cddd88799c5630e7bfa41dc808a792e51c9f660a52d57b5fbbd6d45da462fbd88b291372f4b45f8e0a19a2350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aec7608d7934bc146a42942ff946820b451554b2f875dec78153e9a7e735611880365513ce44b30bae46051234d2eb4216280a8c6edb1aff1abbad9e878b10fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0097f857f02ada5f20a952da16eb78943d9ddfd1e9d4e44eed0823f723deacca18d170db3bb3591fde4178dc88b0ca0e5b5f3b785b4a626a3df718e764a1cdc9"} 05:51:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:49 INFO - Contents: 05:51:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11494c6098f26d7ee6f6be538579f8a456bca23cddd88799c5630e7bfa41dc808a792e51c9f660a52d57b5fbbd6d45da462fbd88b291372f4b45f8e0a19a2350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aec7608d7934bc146a42942ff946820b451554b2f875dec78153e9a7e735611880365513ce44b30bae46051234d2eb4216280a8c6edb1aff1abbad9e878b10fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0097f857f02ada5f20a952da16eb78943d9ddfd1e9d4e44eed0823f723deacca18d170db3bb3591fde4178dc88b0ca0e5b5f3b785b4a626a3df718e764a1cdc9"} 05:51:49 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:49 INFO - Copying logs to upload dir... 05:51:49 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.819827 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-19 05:51:49.388123) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 05:51:49.392326) ========= 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.51HNQDj0yg/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.EVjNFkLDXq/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11494c6098f26d7ee6f6be538579f8a456bca23cddd88799c5630e7bfa41dc808a792e51c9f660a52d57b5fbbd6d45da462fbd88b291372f4b45f8e0a19a2350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aec7608d7934bc146a42942ff946820b451554b2f875dec78153e9a7e735611880365513ce44b30bae46051234d2eb4216280a8c6edb1aff1abbad9e878b10fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0097f857f02ada5f20a952da16eb78943d9ddfd1e9d4e44eed0823f723deacca18d170db3bb3591fde4178dc88b0ca0e5b5f3b785b4a626a3df718e764a1cdc9"} build_url:https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011289 build_url: 'https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/11494c6098f26d7ee6f6be538579f8a456bca23cddd88799c5630e7bfa41dc808a792e51c9f660a52d57b5fbbd6d45da462fbd88b291372f4b45f8e0a19a2350", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aec7608d7934bc146a42942ff946820b451554b2f875dec78153e9a7e735611880365513ce44b30bae46051234d2eb4216280a8c6edb1aff1abbad9e878b10fc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0097f857f02ada5f20a952da16eb78943d9ddfd1e9d4e44eed0823f723deacca18d170db3bb3591fde4178dc88b0ca0e5b5f3b785b4a626a3df718e764a1cdc9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 05:51:49.427546) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:51:49.428136) ========= 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.51HNQDj0yg/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.EVjNFkLDXq/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.005041 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 05:51:49.478605) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 05:51:49.479090) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 05:51:49.479603) ========= ========= Total master_lag: 0.36 =========