builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0073 starttime: 1463655686.42 results: success (0) buildid: 20160519020440 builduid: 0a7d1a9ba3ff4ca2ba68edafaf942fd0 revision: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.423950) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.424477) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.424806) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.444913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.445316) ========= 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.lH8Fn35xAS/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.P3mSBRozDG/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:26-- 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% 7.25M=0.002s 2016-05-19 04:01:26 (7.25 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.557733) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.558090) ========= 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.lH8Fn35xAS/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.P3mSBRozDG/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.027917 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:26.606606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 04:01:26.607001) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 --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 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lH8Fn35xAS/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.P3mSBRozDG/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:26,737 truncating revision to first 12 chars 2016-05-19 04:01:26,737 Setting DEBUG logging. 2016-05-19 04:01:26,737 attempt 1/10 2016-05-19 04:01:26,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5a4cdb6dfb19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 04:01:28,167 unpacking tar archive at: fx-team-5a4cdb6dfb19/testing/mozharness/ program finished with exit code 0 elapsedTime=1.675164 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 04:01:28.342282) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:28.342623) ========= script_repo_revision: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:28.343047) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:28.343317) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 04:01:28.357316) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-19 04:01:28.357640) ========= /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.lH8Fn35xAS/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.P3mSBRozDG/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:28 INFO - MultiFileLogger online at 20160519 04:01:28 in /builds/slave/test 04:01:28 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 04:01:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:01:28 INFO - {'append_to_log': False, 04:01:28 INFO - 'base_work_dir': '/builds/slave/test', 04:01:28 INFO - 'blob_upload_branch': 'Fx-Team', 04:01:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:01:28 INFO - 'branch': 'Fx-Team', 04:01:28 INFO - 'buildbot_json_path': 'buildprops.json', 04:01:28 INFO - 'config_files': ('talos/mac_config.py',), 04:01:28 INFO - 'default_actions': ('clobber', 04:01:28 INFO - 'read-buildbot-config', 04:01:28 INFO - 'download-and-extract', 04:01:28 INFO - 'populate-webroot', 04:01:28 INFO - 'create-virtualenv', 04:01:28 INFO - 'install', 04:01:28 INFO - 'run-tests'), 04:01:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:01:28 INFO - 'download_minidump_stackwalk': True, 04:01:28 INFO - 'download_symbols': 'ondemand', 04:01:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:01:28 INFO - 'tooltool.py': '/tools/tooltool.py', 04:01:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:01:28 INFO - '/tools/misc-python/virtualenv.py')}, 04:01:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:01:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:01:28 INFO - 'installer_path': 'installer.exe', 04:01:28 INFO - 'log_level': 'info', 04:01:28 INFO - 'log_name': 'talos', 04:01:28 INFO - 'log_to_console': True, 04:01:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:01:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:01:28 INFO - 'opt_config_files': (), 04:01:28 INFO - 'pip_index': False, 04:01:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:01:28 INFO - 'cmd': ('bash', 04:01:28 INFO - '-c', 04:01:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:01:28 INFO - 'enabled': True, 04:01:28 INFO - 'halt_on_failure': False, 04:01:28 INFO - 'name': 'check_screen_resolution'},), 04:01:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:01:28 INFO - 'cmd': ('bash', 04:01:28 INFO - '-c', 04:01:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:01:28 INFO - 'enabled': True, 04:01:28 INFO - 'halt_on_failure': False, 04:01:28 INFO - 'name': 'check_screen_resolution'},), 04:01:28 INFO - 'run_cmd_checks_enabled': True, 04:01:28 INFO - 'sps_profile': False, 04:01:28 INFO - 'sps_profile_interval': 0, 04:01:28 INFO - 'suite': 'dromaeojs-e10s', 04:01:28 INFO - 'system_bits': '32', 04:01:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:01:28 INFO - 'title': 't-yosemite-r7-0073', 04:01:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:01:28 INFO - 'use_talos_json': True, 04:01:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:01:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:01:28 INFO - 'work_dir': 'build'} 04:01:28 INFO - ##### 04:01:28 INFO - ##### Running clobber step. 04:01:28 INFO - ##### 04:01:28 INFO - Running pre-action listener: _resource_record_pre_action 04:01:28 INFO - Running main action method: clobber 04:01:28 INFO - rmtree: /builds/slave/test/build 04:01:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:01:30 INFO - Running post-action listener: _resource_record_post_action 04:01:30 INFO - ##### 04:01:30 INFO - ##### Finished clobber step (success) 04:01:30 INFO - ##### 04:01:30 INFO - ##### 04:01:30 INFO - ##### Running read-buildbot-config step. 04:01:30 INFO - ##### 04:01:30 INFO - Running pre-action listener: _resource_record_pre_action 04:01:30 INFO - Running main action method: read_buildbot_config 04:01:30 INFO - Using buildbot properties: 04:01:30 INFO - { 04:01:30 INFO - "project": "", 04:01:30 INFO - "product": "firefox", 04:01:30 INFO - "script_repo_revision": "production", 04:01:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 04:01:30 INFO - "repository": "", 04:01:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 04:01:30 INFO - "buildid": "20160519020440", 04:01:30 INFO - "pgo_build": "False", 04:01:30 INFO - "basedir": "/builds/slave/test", 04:01:30 INFO - "buildnumber": 673, 04:01:30 INFO - "slavename": "t-yosemite-r7-0073", 04:01:30 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:01:30 INFO - "platform": "yosemite_r7", 04:01:30 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 04:01:30 INFO - "branch": "fx-team", 04:01:30 INFO - "repo_path": "integration/fx-team", 04:01:30 INFO - "revision": "5a4cdb6dfb19b458229c60e0e19f083ba83d0f58", 04:01:30 INFO - "stage_platform": "macosx64", 04:01:30 INFO - "builduid": "0a7d1a9ba3ff4ca2ba68edafaf942fd0", 04:01:30 INFO - "slavebuilddir": "test" 04:01:30 INFO - } 04:01:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:01:30 INFO - Running post-action listener: _resource_record_post_action 04:01:30 INFO - ##### 04:01:30 INFO - ##### Finished read-buildbot-config step (success) 04:01:30 INFO - ##### 04:01:30 INFO - ##### 04:01:30 INFO - ##### Running download-and-extract step. 04:01:30 INFO - ##### 04:01:30 INFO - Running pre-action listener: _resource_record_pre_action 04:01:30 INFO - Running main action method: download_and_extract 04:01:30 INFO - mkdir: /builds/slave/test/build/tests 04:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:30 INFO - https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:01:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:01:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:01:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:33 INFO - Downloaded 1199 bytes. 04:01:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:01:33 INFO - Using the following test package requirements: 04:01:33 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:01:33 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:01:33 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:01:33 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'jsshell-mac.zip'], 04:01:33 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:01:33 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:01:33 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:01:33 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:01:33 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:01:33 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:01:33 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:01:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:01: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')]} 04:01:33 INFO - https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:01:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:01:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:01:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:34 INFO - Downloaded 18628937 bytes. 04:01:34 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'] 04:01:34 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 04:01:37 INFO - Return code: 0 04:01:37 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 04:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:37 INFO - https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:01:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:01:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:01:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:38 INFO - Downloaded 18628937 bytes. 04:01:38 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'] 04:01:38 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 04:01:42 INFO - Return code: 0 04:01:42 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')]} 04:01:42 INFO - https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:01:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:01:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:01:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:43 INFO - Downloaded 10840194 bytes. 04:01:43 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'] 04:01:43 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 04:01:43 INFO - Return code: 0 04:01:43 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')]} 04:01:43 INFO - https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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 04:01:47 INFO - Downloaded 110309060 bytes. 04:01:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:01:47 INFO - mkdir: /builds/slave/test/properties 04:01:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:01:47 INFO - Writing to file /builds/slave/test/properties/build_url 04:01:47 INFO - Contents: 04:01:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:01:48 INFO - Running post-action listener: _resource_record_post_action 04:01:48 INFO - Running post-action listener: set_extra_try_arguments 04:01:48 INFO - ##### 04:01:48 INFO - ##### Finished download-and-extract step (success) 04:01:48 INFO - ##### 04:01:48 INFO - ##### 04:01:48 INFO - ##### Running populate-webroot step. 04:01:48 INFO - ##### 04:01:48 INFO - Running pre-action listener: _resource_record_pre_action 04:01:48 INFO - Running main action method: populate_webroot 04:01:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:01:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:01:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:01:48 INFO - '--tppagecycles', 04:01:48 INFO - '7'], 04:01:48 INFO - 'tests': ['tsvgm']}}, 04:01:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:01:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:01:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:01:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:01:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:01:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['damp', 'tps']}, 04:01:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'tests': ['damp', 'tps']}, 04:01:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['dromaeo_dom']}, 04:01:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:01:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['a11yr', 04:01:48 INFO - 'ts_paint', 04:01:48 INFO - 'tpaint', 04:01:48 INFO - 'sessionrestore', 04:01:48 INFO - 'sessionrestore_no_auto_restore', 04:01:48 INFO - 'tabpaint']}, 04:01:48 INFO - 'other-e10s': {'tests': ['a11yr', 04:01:48 INFO - 'ts_paint', 04:01:48 INFO - 'tpaint', 04:01:48 INFO - 'sessionrestore', 04:01:48 INFO - 'sessionrestore_no_auto_restore', 04:01:48 INFO - 'tabpaint']}, 04:01:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:01:48 INFO - 'ts_paint', 04:01:48 INFO - 'tpaint', 04:01:48 INFO - 'sessionrestore', 04:01:48 INFO - 'sessionrestore_no_auto_restore', 04:01:48 INFO - 'tabpaint']}, 04:01:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:01:48 INFO - 'ts_paint', 04:01:48 INFO - 'tpaint', 04:01:48 INFO - 'sessionrestore', 04:01:48 INFO - 'sessionrestore_no_auto_restore', 04:01:48 INFO - 'tabpaint']}, 04:01:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['a11yr', 04:01:48 INFO - 'ts_paint', 04:01:48 INFO - 'tpaint', 04:01:48 INFO - 'sessionrestore', 04:01:48 INFO - 'sessionrestore_no_auto_restore', 04:01:48 INFO - 'tabpaint']}, 04:01:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['a11yr', 04:01:48 INFO - 'ts_paint', 04:01:48 INFO - 'tpaint', 04:01:48 INFO - 'sessionrestore', 04:01:48 INFO - 'sessionrestore_no_auto_restore', 04:01:48 INFO - 'tabpaint']}, 04:01:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['tsvgx', 04:01:48 INFO - 'tsvgr_opacity', 04:01:48 INFO - 'tart', 04:01:48 INFO - 'tscrollx', 04:01:48 INFO - 'cart']}, 04:01:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:01:48 INFO - 'tsvgr_opacity', 04:01:48 INFO - 'tart', 04:01:48 INFO - 'tscrollx', 04:01:48 INFO - 'cart']}, 04:01:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'talos_options': ['--disable-e10s'], 04:01:48 INFO - 'tests': ['tp5o']}, 04:01:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'tests': ['tp5o']}, 04:01:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'talos_options': ['--disable-e10s', 04:01:48 INFO - '--xperf_path', 04:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:48 INFO - 'tests': ['tp5n']}, 04:01:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:01:48 INFO - 'talos_options': ['--xperf_path', 04:01:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:01:48 INFO - 'tests': ['tp5n']}}, 04:01:48 INFO - 'talos.zip': {'path': '', 04:01:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:01:48 INFO - Running post-action listener: _resource_record_post_action 04:01:48 INFO - ##### 04:01:48 INFO - ##### Finished populate-webroot step (success) 04:01:48 INFO - ##### 04:01:48 INFO - ##### 04:01:48 INFO - ##### Running create-virtualenv step. 04:01:48 INFO - ##### 04:01:48 INFO - Running pre-action listener: _resource_record_pre_action 04:01:48 INFO - Running main action method: create_virtualenv 04:01:48 INFO - Creating virtualenv /builds/slave/test/build/venv 04:01:48 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 04:01:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:01:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:01:48 INFO - Using real prefix '/tools/python27' 04:01:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:01:49 INFO - Installing distribute.............................................................................................................................................................................................done. 04:01:52 INFO - Installing pip.................done. 04:01:52 INFO - Return code: 0 04:01:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:52 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 04:01:52 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 04:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:52 INFO - 'HOME': '/Users/cltbld', 04:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:52 INFO - 'LOGNAME': 'cltbld', 04:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:52 INFO - 'MOZ_NO_REMOTE': '1', 04:01:52 INFO - 'NO_EM_RESTART': '1', 04:01:52 INFO - 'PAGER': '/bin/cat', 04:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:52 INFO - 'PWD': '/builds/slave/test', 04:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:52 INFO - 'SHELL': '/bin/bash', 04:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:01:52 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:52 INFO - 'USER': 'cltbld', 04:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:52 INFO - 'XPC_FLAGS': '0x0', 04:01:52 INFO - 'XPC_SERVICE_NAME': '0', 04:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:52 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 04:01:52 INFO - Cleaning up... 04:01:52 INFO - Return code: 0 04:01:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:52 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:52 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 04:01:52 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 04:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:52 INFO - 'HOME': '/Users/cltbld', 04:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:52 INFO - 'LOGNAME': 'cltbld', 04:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:52 INFO - 'MOZ_NO_REMOTE': '1', 04:01:52 INFO - 'NO_EM_RESTART': '1', 04:01:52 INFO - 'PAGER': '/bin/cat', 04:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:52 INFO - 'PWD': '/builds/slave/test', 04:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:52 INFO - 'SHELL': '/bin/bash', 04:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:01:52 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:52 INFO - 'USER': 'cltbld', 04:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:52 INFO - 'XPC_FLAGS': '0x0', 04:01:52 INFO - 'XPC_SERVICE_NAME': '0', 04:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:52 INFO - Downloading/unpacking psutil>=3.1.1 04:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:01:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:55 INFO - Installing collected packages: psutil 04:01:55 INFO - Running setup.py install for psutil 04:01:55 INFO - building 'psutil._psutil_osx' extension 04:01:55 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 04:01:56 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 04:01:56 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 04:01:56 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 04:01:56 INFO - building 'psutil._psutil_posix' extension 04:01:56 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 04:01:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:01:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:01:56 INFO - ^ 04:01:56 INFO - 1 warning generated. 04:01:56 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 04:01:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:01:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:01:56 INFO - Successfully installed psutil 04:01:56 INFO - Cleaning up... 04:01:56 INFO - Return code: 0 04:01:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:56 INFO - 'HOME': '/Users/cltbld', 04:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:56 INFO - 'LOGNAME': 'cltbld', 04:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:56 INFO - 'MOZ_NO_REMOTE': '1', 04:01:56 INFO - 'NO_EM_RESTART': '1', 04:01:56 INFO - 'PAGER': '/bin/cat', 04:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:56 INFO - 'PWD': '/builds/slave/test', 04:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:56 INFO - 'SHELL': '/bin/bash', 04:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:01:56 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:56 INFO - 'USER': 'cltbld', 04:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:56 INFO - 'XPC_FLAGS': '0x0', 04:01:56 INFO - 'XPC_SERVICE_NAME': '0', 04:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:01:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:01:57 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 04:01:57 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 04:01:57 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 04:01:57 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 04:01:57 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 04:01:57 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 04:01:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:01:59 INFO - Installing collected packages: mozsystemmonitor 04:01:59 INFO - Running setup.py install for mozsystemmonitor 04:01:59 INFO - Successfully installed mozsystemmonitor 04:01:59 INFO - Cleaning up... 04:01:59 INFO - Return code: 0 04:01:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:01:59 INFO - 'HOME': '/Users/cltbld', 04:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:01:59 INFO - 'LOGNAME': 'cltbld', 04:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:59 INFO - 'MOZ_NO_REMOTE': '1', 04:01:59 INFO - 'NO_EM_RESTART': '1', 04:01:59 INFO - 'PAGER': '/bin/cat', 04:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:59 INFO - 'PWD': '/builds/slave/test', 04:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:01:59 INFO - 'SHELL': '/bin/bash', 04:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:01:59 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:01:59 INFO - 'USER': 'cltbld', 04:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:59 INFO - 'XPC_FLAGS': '0x0', 04:01:59 INFO - 'XPC_SERVICE_NAME': '0', 04:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:02:00 INFO - Downloading/unpacking blobuploader==1.2.4 04:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:02 INFO - Downloading blobuploader-1.2.4.tar.gz 04:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:02:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:02:02 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 04:02:02 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 04:02:02 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 04:02:02 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 04:02:02 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 04:02:02 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 04:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:02:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:03 INFO - Downloading docopt-0.6.1.tar.gz 04:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:02:03 INFO - Installing collected packages: blobuploader, requests, docopt 04:02:03 INFO - Running setup.py install for blobuploader 04:02:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:02:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:02:04 INFO - Running setup.py install for requests 04:02:04 INFO - Running setup.py install for docopt 04:02:04 INFO - Successfully installed blobuploader requests docopt 04:02:04 INFO - Cleaning up... 04:02:04 INFO - Return code: 0 04:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:04 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:02:04 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 04:02:04 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 04:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:04 INFO - 'HOME': '/Users/cltbld', 04:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:04 INFO - 'LOGNAME': 'cltbld', 04:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:04 INFO - 'MOZ_NO_REMOTE': '1', 04:02:04 INFO - 'NO_EM_RESTART': '1', 04:02:04 INFO - 'PAGER': '/bin/cat', 04:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:04 INFO - 'PWD': '/builds/slave/test', 04:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:04 INFO - 'SHELL': '/bin/bash', 04:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:02:04 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:04 INFO - 'USER': 'cltbld', 04:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:04 INFO - 'XPC_FLAGS': '0x0', 04:02:04 INFO - 'XPC_SERVICE_NAME': '0', 04:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-XQ8Drz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-IfApV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-kbEsnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-8voA0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-neMubK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-XeaVce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-pVoFx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-MLYsKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-XMRcdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:02:05 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-SLtfx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-nXMO3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-t8iXTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-NLJkGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-5q75zS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-gnalid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-QnqdD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:02:06 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-GcQbOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:02:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:02:06 INFO - Running setup.py install for manifestparser 04:02:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:02:06 INFO - Running setup.py install for mozcrash 04:02:07 INFO - Running setup.py install for mozdebug 04:02:07 INFO - Running setup.py install for mozdevice 04:02:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Running setup.py install for mozfile 04:02:07 INFO - Running setup.py install for mozhttpd 04:02:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Running setup.py install for mozinfo 04:02:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Running setup.py install for mozInstall 04:02:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:02:07 INFO - Running setup.py install for mozleak 04:02:08 INFO - Running setup.py install for mozlog 04:02:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:02:08 INFO - Running setup.py install for moznetwork 04:02:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:02:08 INFO - Running setup.py install for mozprocess 04:02:08 INFO - Running setup.py install for mozprofile 04:02:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:02:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:02:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:02:08 INFO - Running setup.py install for mozrunner 04:02:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:02:08 INFO - Running setup.py install for mozscreenshot 04:02:09 INFO - Running setup.py install for moztest 04:02:09 INFO - Running setup.py install for mozversion 04:02:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:02:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:02:09 INFO - Cleaning up... 04:02:09 INFO - Return code: 0 04:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:09 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:02:09 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 04:02:09 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 04:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:09 INFO - 'HOME': '/Users/cltbld', 04:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:09 INFO - 'LOGNAME': 'cltbld', 04:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:09 INFO - 'MOZ_NO_REMOTE': '1', 04:02:09 INFO - 'NO_EM_RESTART': '1', 04:02:09 INFO - 'PAGER': '/bin/cat', 04:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:09 INFO - 'PWD': '/builds/slave/test', 04:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:09 INFO - 'SHELL': '/bin/bash', 04:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:02:09 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:09 INFO - 'USER': 'cltbld', 04:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:09 INFO - 'XPC_FLAGS': '0x0', 04:02:09 INFO - 'XPC_SERVICE_NAME': '0', 04:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:02:09 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-xBSTtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:02:09 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)) 04:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:02:09 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-oDybQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:02:09 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)) 04:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:02:09 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Mbywfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:02:09 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)) 04:02:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:02:09 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-nZvhzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-3m2fR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-4TprvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-uoP4H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-MQ_anB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-yeTG3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-HTDs4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-LBALdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-bai3RY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:02:10 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-UF230v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:02:10 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)) 04:02:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:02:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-kGY5zL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:02:11 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)) 04:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:02:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-0GGVNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:02:11 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)) 04:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:02:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-19_Qjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:02:11 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)) 04:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:02:11 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-rGsSAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:02:11 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)) 04:02:11 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)) 04:02:11 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)) 04:02:11 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)) 04:02:11 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)) 04:02:11 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)) 04:02:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:02: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 04:02: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 04:02: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 04:02: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 04:02: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 04:02: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 04:02:13 INFO - Downloading blessings-1.6.tar.gz 04:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:02:13 INFO - Installing collected packages: blessings 04:02:13 INFO - Running setup.py install for blessings 04:02:14 INFO - Successfully installed blessings 04:02:14 INFO - Cleaning up... 04:02:14 INFO - Return code: 0 04:02:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:02:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:02:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:02:14 INFO - Reading from file tmpfile_stdout 04:02:14 INFO - Current package versions: 04:02:14 INFO - blessings == 1.6 04:02:14 INFO - blobuploader == 1.2.4 04:02:14 INFO - docopt == 0.6.1 04:02:14 INFO - manifestparser == 1.1 04:02:14 INFO - mozInstall == 1.12 04:02:14 INFO - mozcrash == 0.17 04:02:14 INFO - mozdebug == 0.1 04:02:14 INFO - mozdevice == 0.48 04:02:14 INFO - mozfile == 1.2 04:02:14 INFO - mozhttpd == 0.7 04:02:14 INFO - mozinfo == 0.9 04:02:14 INFO - mozleak == 0.1 04:02:14 INFO - mozlog == 3.2 04:02:14 INFO - moznetwork == 0.27 04:02:14 INFO - mozprocess == 0.22 04:02:14 INFO - mozprofile == 0.28 04:02:14 INFO - mozrunner == 6.11 04:02:14 INFO - mozscreenshot == 0.1 04:02:14 INFO - mozsystemmonitor == 0.3 04:02:14 INFO - moztest == 0.7 04:02:14 INFO - mozversion == 1.4 04:02:14 INFO - psutil == 3.1.1 04:02:14 INFO - requests == 1.2.3 04:02:14 INFO - wsgiref == 0.1.2 04:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:14 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:02:14 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 04:02:14 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 04:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:14 INFO - 'HOME': '/Users/cltbld', 04:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:14 INFO - 'LOGNAME': 'cltbld', 04:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:14 INFO - 'MOZ_NO_REMOTE': '1', 04:02:14 INFO - 'NO_EM_RESTART': '1', 04:02:14 INFO - 'PAGER': '/bin/cat', 04:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:14 INFO - 'PWD': '/builds/slave/test', 04:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:14 INFO - 'SHELL': '/bin/bash', 04:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:02:14 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:14 INFO - 'USER': 'cltbld', 04:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:14 INFO - 'XPC_FLAGS': '0x0', 04:02:14 INFO - 'XPC_SERVICE_NAME': '0', 04:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 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)) 04:02:14 INFO - Cleaning up... 04:02:14 INFO - Return code: 0 04:02:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:02:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:02:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:14 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:02:14 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 04:02:14 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 04:02:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:02:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:14 INFO - 'HOME': '/Users/cltbld', 04:02:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:14 INFO - 'LOGNAME': 'cltbld', 04:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:14 INFO - 'MOZ_NO_REMOTE': '1', 04:02:14 INFO - 'NO_EM_RESTART': '1', 04:02:14 INFO - 'PAGER': '/bin/cat', 04:02:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:14 INFO - 'PWD': '/builds/slave/test', 04:02:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:14 INFO - 'SHELL': '/bin/bash', 04:02:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:02:14 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:02:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:14 INFO - 'USER': 'cltbld', 04:02:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:14 INFO - 'XPC_FLAGS': '0x0', 04:02:14 INFO - 'XPC_SERVICE_NAME': '0', 04:02:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:02:14 INFO - Downloading/unpacking jsonschema 04:02:14 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 04:02:14 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 04:02:14 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 04:02:14 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 04:02:14 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 04:02:14 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 04:02:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:02:17 INFO - Downloading/unpacking functools32 (from jsonschema) 04:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:17 INFO - Downloading functools32-3.2.3-2.tar.gz 04:02:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:02:17 INFO - warning: no files found matching '*.txt' 04:02:17 INFO - no previously-included directories found matching 'build' 04:02:17 INFO - no previously-included directories found matching 'dist' 04:02:17 INFO - no previously-included directories found matching '.git*' 04:02:17 INFO - Installing collected packages: jsonschema, functools32 04:02:17 INFO - Running setup.py install for functools32 04:02:18 INFO - warning: no files found matching '*.txt' 04:02:18 INFO - no previously-included directories found matching 'build' 04:02:18 INFO - no previously-included directories found matching 'dist' 04:02:18 INFO - no previously-included directories found matching '.git*' 04:02:18 INFO - Successfully installed jsonschema functools32 04:02:18 INFO - Cleaning up... 04:02:18 INFO - Return code: 0 04:02:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:02:18 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')]} 04:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:18 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')]} 04:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:02:18 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 0x10d457a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f990b5d2fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, '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.lH8Fn35xAS/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.P3mSBRozDG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 04:02:18 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 04:02:18 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 04:02:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:02:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:18 INFO - 'HOME': '/Users/cltbld', 04:02:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:18 INFO - 'LOGNAME': 'cltbld', 04:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:18 INFO - 'MOZ_NO_REMOTE': '1', 04:02:18 INFO - 'NO_EM_RESTART': '1', 04:02:18 INFO - 'PAGER': '/bin/cat', 04:02:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:18 INFO - 'PWD': '/builds/slave/test', 04:02:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:18 INFO - 'SHELL': '/bin/bash', 04:02:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:02:18 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:02:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:18 INFO - 'USER': 'cltbld', 04:02:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:18 INFO - 'XPC_FLAGS': '0x0', 04:02:18 INFO - 'XPC_SERVICE_NAME': '0', 04:02:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:02:18 INFO - Downloading/unpacking flake8 04:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:02:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:02:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:02:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:02:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 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 04:02:21 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 04:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:02:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:02:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:02:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:02:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:02:22 INFO - no previously-included directories found matching 'docs/_build' 04:02:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:02:22 INFO - Running setup.py install for pep8 04:02:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:02:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:02:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:02:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:02:22 INFO - no previously-included directories found matching 'docs/_build' 04:02:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:02:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:02:22 INFO - Cleaning up... 04:02:22 INFO - Return code: 0 04:02:22 INFO - Running post-action listener: _resource_record_post_action 04:02:22 INFO - Running post-action listener: _start_resource_monitoring 04:02:22 INFO - Starting resource monitoring. 04:02:22 INFO - ##### 04:02:22 INFO - ##### Finished create-virtualenv step (success) 04:02:22 INFO - ##### 04:02:22 INFO - ##### 04:02:22 INFO - ##### Running install step. 04:02:22 INFO - ##### 04:02:22 INFO - Running pre-action listener: _resource_record_pre_action 04:02:22 INFO - Running main action method: install 04:02:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:02:22 INFO - Reading from file tmpfile_stdout 04:02:22 INFO - Detecting whether we're running mozinstall >=1.0... 04:02:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:02:23 INFO - Reading from file tmpfile_stdout 04:02:23 INFO - Output received: 04:02:23 INFO - Usage: mozinstall [options] installer 04:02:23 INFO - Options: 04:02:23 INFO - -h, --help show this help message and exit 04:02:23 INFO - -d DEST, --destination=DEST 04:02:23 INFO - Directory to install application into. [default: 04:02:23 INFO - "/builds/slave/test"] 04:02:23 INFO - --app=APP Application being installed. [default: firefox] 04:02:23 INFO - mkdir: /builds/slave/test/build/application 04:02:23 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'] 04:02:23 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 04:02:56 INFO - Reading from file tmpfile_stdout 04:02:56 INFO - Output received: 04:02:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:02:56 INFO - Running post-action listener: _resource_record_post_action 04:02:56 INFO - ##### 04:02:56 INFO - ##### Finished install step (success) 04:02:56 INFO - ##### 04:02:56 INFO - ##### 04:02:56 INFO - ##### Running run-tests step. 04:02:56 INFO - ##### 04:02:56 INFO - Running pre-action listener: _resource_record_pre_action 04:02:56 INFO - Running main action method: run_tests 04:02:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:02:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:02:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:02:56 INFO - 2016-05-19 04:02:56.506 screenresolution[1638:7646] starting screenresolution argv=screenresolution get 04:02:56 INFO - 2016-05-19 04:02:56.508 screenresolution[1638:7646] Display 0: 1600x1200x32@60 04:02:56 INFO - 2016-05-19 04:02:56.514 screenresolution[1639:7648] starting screenresolution argv=screenresolution list 04:02:56 INFO - Available Modes on Display 0 04:02:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:02:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:02:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:02:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:02:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:02:56 INFO - Intel Iris: 04:02:56 INFO - Chipset Model: Intel Iris 04:02:56 INFO - Type: GPU 04:02:56 INFO - Bus: Built-In 04:02:56 INFO - VRAM (Dynamic, Max): 1536 MB 04:02:56 INFO - Vendor: Intel (0x8086) 04:02:56 INFO - Device ID: 0x0a2e 04:02:56 INFO - Revision ID: 0x0009 04:02:56 INFO - Displays: 04:02:56 INFO - Display: 04:02:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:02:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:02:56 INFO - Display Serial Number: 1600x1200 60 04:02:56 INFO - Main Display: Yes 04:02:56 INFO - Mirror: Off 04:02:56 INFO - Online: Yes 04:02:56 INFO - Rotation: Supported 04:02:56 INFO - Return code: 0 04:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:02:56 INFO - Python 2.7.3 04:02:56 INFO - Return code: 0 04:02:56 INFO - grabbing minidump binary from tooltool 04:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:02:56 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 0x10d2fbd30>, '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 0x10d11fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:02:56 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 04:02:56 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 04:02:56 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 04:02:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:02:56 INFO - Return code: 0 04:02:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:02:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:02:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:02:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:02:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:02:57 INFO - Return code: 0 04:02:57 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/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 04:02:57 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/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0073 --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 04:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lH8Fn35xAS/Render', 04:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:02:57 INFO - 'HOME': '/Users/cltbld', 04:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:02:57 INFO - 'LOGNAME': 'cltbld', 04:02:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:02:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:02:57 INFO - 'MOZ_NO_REMOTE': '1', 04:02:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:02:57 INFO - 'NO_EM_RESTART': '1', 04:02:57 INFO - 'PAGER': '/bin/cat', 04:02:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:02:57 INFO - 'PWD': '/builds/slave/test', 04:02:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:02:57 INFO - 'SHELL': '/bin/bash', 04:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P3mSBRozDG/Listeners', 04:02:57 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 04:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:02:57 INFO - 'USER': 'cltbld', 04:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:02:57 INFO - 'XPC_FLAGS': '0x0', 04:02:57 INFO - 'XPC_SERVICE_NAME': '0', 04:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:02:57 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/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 04:02:58 INFO - mozversion application_buildid: 20160519020440 04:02:58 INFO - mozversion application_changeset: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 04:02:58 INFO - mozversion application_display_name: Nightly 04:02:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:02:58 INFO - mozversion application_name: Firefox 04:02:58 INFO - mozversion application_remotingname: firefox 04:02:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:02:58 INFO - mozversion application_vendor: Mozilla 04:02:58 INFO - mozversion application_version: 49.0a1 04:02:58 INFO - mozversion platform_buildid: 20160519020440 04:02:58 INFO - mozversion platform_changeset: 5a4cdb6dfb19b458229c60e0e19f083ba83d0f58 04:02:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:02:58 INFO - mozversion platform_version: 49.0a1 04:02:58 INFO - using testdate: 1463655778 04:02:58 INFO - actual date: 1463655778 04:02:58 INFO - starting webserver on 'localhost:49276' 04:02:58 INFO - SUITE-START | Running 2 tests 04:02:58 INFO - TEST-START | dromaeo_css 04:02:58 INFO - operating with platform_type : mac_ 04:02:58 INFO - Initialising browser for dromaeo_css test... 04:02:58 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpUrhVTp/profile http://localhost:49276/getInfo.html) 04:03:01 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:03:01 INFO - PROCESS | 1657 | colorDepth:24 04:03:01 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:03:01 INFO - PROCESS | 1657 | __metrics 04:03:01 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 04:03:02 INFO - TEST-INFO | 1657: exit 0 04:03:02 INFO - Browser initialized. 04:03:02 INFO - Running cycle 1/1 for dromaeo_css test... 04:03:02 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpUrhVTp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:03:14 INFO - PROCESS | 1660 | RSS: Main: 249397248 04:03:14 INFO - PROCESS | 1660 | 04:04:03 INFO - PROCESS | 1660 | 1463655843808 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] 04:06:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 04:06:15 INFO - PROCESS | 1660 | RSS: Main: 263139328 04:06:15 INFO - PROCESS | 1660 | 04:09:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 04:09:18 INFO - PROCESS | 1660 | RSS: Main: 274677760 04:09:18 INFO - PROCESS | 1660 | 04:12:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 04:12:20 INFO - PROCESS | 1660 | RSS: Main: 276664320 04:12:20 INFO - PROCESS | 1660 | 04:15:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 04:15:23 INFO - PROCESS | 1660 | RSS: Main: 278216704 04:15:23 INFO - PROCESS | 1660 | 04:18:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 04:18:26 INFO - PROCESS | 1660 | RSS: Main: 281145344 04:18:26 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 04:21:29 INFO - PROCESS | 1660 | RSS: Main: 282329088 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | __start_tp_report 04:21:29 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:21:29 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:21:29 INFO - PROCESS | 1660 | |i|pagename|runs| 04:21:29 INFO - PROCESS | 1660 | |0;dojo.html;2613.803964702647;3471.5486986691726;3499.9125021874434;3520.0671821967167;3529.8058606776617;5773.42265773423;5807.970960145204;5925.85185370366;5939.673317967516;5972.223610930584;4835.347228124202;5096.898062038757;5872.559558033143;5915.615484993473;5976;5011.574016208627;5287.762050707727;5365.812196573121;5371.086915224421;5432.483914028161;434.2914086081933;5231.476852314776;5299.629025968184;5309.787608495675;5331.466853314657;5209.400918894319;5300.310959575249;5325.733713314319;5359.892802143935;5372.167314066326;5353.518183363516;5377;5386.596005299618;5392.05639013175;5417.674939503642;4382.649388048949;5183.714895680736;5258.605604579634;5301.257823904656;5329.866753331159;5582.609217354746;5724.513416359573;5734.369219385864;5790.565707571906;5808.099744539603;6191.25704915413;6246.187995560548;6288.119663247149;6322.747090116436;6325.272593651697;6202.658853763041;6264.091706702503;6271.843203919893;6328;6346.238451385863;6034.698265086772;6073.757049718006;6080.057591073347;6088.634681919099;6096.993995990656;4950.25746138075;5906.995810712189;6365.427111559982;6397.136386587844;6399.488040956712;5160.819371321985;6207.906881396782;6223.813285601439;6259.7496100156395;6260.060990851318;6209.223847019122;6289.7484100636375;6318.147050148263;6343.651099189497;6362.840928976812;5103;6157.476614487819;6272;6307.211598550181;6324.588901721466;6226.844328891814;6258.405451482102;6278.811635650938;6294.433500984934;6297.559170857996;5102.030614183293;5110.897782044338;5123.001014802077;5130.615203859726;5148.734556794998;4447.08834688888;4451.465824101128;4488.147252022105;4529.932051019237;4596.540345965377;5939.168516407706;5951.345352011275;6054.545909056838;6055.455009049118;6081.604695694766;6296.710762959136;6342.619442833445;6353.30113687494;6356.555041147075;6361.332060133712;5840.3575606683235;5933.554983376264;5966.224390829251;6013.51891848651;6040.244969378828;5512.80705175317;5536.833894983077;5547.667139971614;5552.3892371839065;5556.861078473071;5392.299001129828;6537.640429776313;6549.181352330958;6562.081308616797;6575.243846957565;5214.817481388133;6221.253449585897;6268.843278918063;6284.874302514016;6338.904916426165;6555.246146693191;6559.770408035696;6560.901586476302;6573.1454910861285;6577.6053436793945;6195.411435913581;6302.369763023844;6306.085617585516;6314.210723659543;6335.683215839097;4686.554777296152;5750.562359410147;6263.906041409474;6284.874302514016;6345.777897773556;6260.280067792262;6279.183706118175;6281.811545653638;6293.496520278275;6321.083442900662;6075.939240607714;6152.138700582012;6197.907031394442;6206.751729930933;6226.128342032029;5204.724842413481;5473.67157970523;5535.833924982257;6122.387761223842;6125.081237814364;5996.610220335664;6290.433860952537;6298.275698294571;6305.243370795534;6318.462930650946;6034.879302414015;6132.814448934826;6142.477889379274;6156.784512542219;6190.473809726223 04:21:29 INFO - PROCESS | 1660 | |1;ext.html;511.57283668137103;516.6796586116606;521.4993606138107;536.656539814519;596.5972968246433;39205.41191882124;42326;42327.15345693088;42404.57595424045;42419.57580424195;37703.05742356442;42993.785031074876;43611.6916492505;43672;43888;9585.233181345493;9728.951355243214;9735.902640973605;9804.725385699849;9845.36005159666;8307.501549907025;9608.80782384352;9662.275329350292;9671.758206044835;9774.218062554979;9712.66005689801;9718.70843874685;9747.951260243688;9790.70627881161;9799.559019844124;9736.318457708;9753.707388778348;9778.706638800848;9794.16749576287;9801.411915285105;9712.61149554017;9728.805423891483;9744.61021559144;9820;9827.606895724162;40599.797001014806;40635.98410039743;40981.1803763926;41025.38461923073;41039.7948010261;2877.8848846046135;3106.2544989202656;3156.921076973071;3416.5900091988874;3456.1359660084977;27397.45205095887;28367;28472;29060.854695726386;29165.83336666531;2649.284693132843;2687.153546632824;2726.8772905219116;2782.3600571868583;2797.5104356737693;385511;386727;387079;389295;391421.99999999715;29449.85275073611;34520.654793452246;35250.823745881105;35498;35624.821875890455;28462.288442789097;35112.82443587766;36337.27325453476;36472.08819779527;36499.35006499382;14919.850801491846;15189.924050379677;15192.240387980777;15287.92984491075;15364.231788410534;12505.562305319452;12525.436355363849;12957.870421295855;12967;13001.544945927037;7962.482438641586;8048.4366094374;8141.430099893069;8183.427160098736;8227.753167404988;2746.368335282856;2852.217364147408;3377.2907689385015;3428.3828910795796;3438.484227365865;834.6661335465741;847.7583888591845;987.7678745494803;1060.7825395793843;1066.594694016269;28994.565081523793;29256.26859328534;29414;30134;30553.694463055086;1114.2144787924494;1118.552578968419;1134.432783608196;1143.9942800285946;1170.39724541861;15306.234688265542;15320.770188447183;15458.420576579882;15607.765883511529;15705.371785128695;515273;559841.4015859871;592677;595761.0211948913;596962;57993.13010304849;58061.70969145127;58815.41184588185;58933.11600325999;58939;1479.6448852275375;1484.5397926642775;1484.547213100015;1487.263804416799;1498.460554200487;29077;29241.561376578942;29338.706612934453;29849;29902.953396631012;50665.49334506755;51579.48420515747;52109;52137;52199.21701174409;508396;508728;510123;521422;525006.3747131312;25536.48927021412;25723.742762572136;25779.097731579306;26364.07725729667;26693.866530667998;44400;44734.10531789411;45229.321560175966;45351.546484536055;45363.31955020611;29586.556201656564;36431.81784091169;36622.450663240605;36964.63035369612;37308;31706.524402133524;37334.43998339973;37368.25263494769;38094.23811523809;38234.23531529298;32376;36092.458613120296;36611.81694091512;36867;38714.22571548609;25748.742512575383;32734;33276.500852486744;33368.23839157488;34002;20114.497137571678;24019.159329423386;24744.133955311478;25633.231003069937;25744.356391090372 04:21:29 INFO - PROCESS | 1660 | |2;jquery.html;347.5707501235974;389.43337444018937;399.5584878709026;407.9306517891958;414.3039693315231;19152;21063.788302228277;21339.67990480144;21446;21462.678059829097;17775.911120444413;20555.177792888306;21343.893280533615;21627.81047042416;21668;5333.626646134772;5968.999999999989;6163.383661633823;6165.198524191849;6200;5914.467697907187;6147.293061297941;6193.566450348478;6207.56547041705;6209.037599172136;5938.079597662348;6073.969630151865;6100;6110.694465276741;6111;5578.30271216098;5983.820485385445;6087.969560152215;6103.542234332421;6118.877622447549;5639.379668236491;5796.478316951495;5799.536037117021;5810.360860305363;5828;13545.255010974393;13688.589342319747;13853.584392468241;13858.722825543433;13904.58286251414;1797.9640407191782;1978.357033963953;2213.1368766181204;2262.4343914021497;2273.0453209651987;9835.803283934281;9848.556814943273;9933.155681767059;10098.203353407147;10098.949505252427;1740.1125426032688;1810.94567162984;1815.582416044304;1819.417786308382;1826.4794533558006;110561;115608.42195789052;116121;118663;118771;9448.385854919408;11929.985951194245;12100.69748256283;12163;12393.566225182076;15076.396944122112;15098.320575574126;15122.319495622723;15128.697426051418;15134.318955647022;8618.956905215433;8728.956355218183;8841.778955526162;8892.8666070009;8956.283497320199;8801.51591662452;8832.425892316978;8863.822723545492;8881.600327985256;9002.954985225162;4596.448426188812;4597.126545956257;4677.462091859412;4706.294055891575;4709.764511774398;1968.8326492248034;2017.1729590867967;2031.5733695923725;2037.5008123009661;2058.3310424112224;818.5088946631973;1016.6797458800454;1016.9491525423847;1020.8056573808661;1045.0490054050929;17333.4799955999;18191.090445477672;20520.973951302374;20558.486037849172;21193.576128477343;930.8689941720706;967.8209531236603;981.7741919358444;983.6409710455632;993.6919554937992;10945.398003109745;10997.094303944807;11163.83254251187;11239.775204495865;11251.381174035343;535395;537901;541695;614797;626469;132344;133415;134460;137752;140125.19749605155;991.4596544883057;994.2990191914682;1015.7257540464034;1016.1819735113157;1057.3074636113204;19383.83696978186;19623.80376196277;19707.90146049318;19708;19819.60360792747;55957.720211398686;56427.43572564386;57344.42655573391;57348.42651573598;58065.709671451375;82726;83288.58355708425;83463.3307333862;85326.57336713276;85542;6621.370969757865;8070.5964701763505;8084.231997960184;8116.553589552395;8255.711050113217;10310.43292618913;12112.273263604213;12215.69460763488;12306.815397768862;12652.810207846705;10610.310329828537;10708.196885233483;10790.4255372078;10828.729281768019;11037.06184974254;10396.064354208158;10650.520726567169;10684.038436540748;10977.12183025372;11069.391183484986;8122.431429799857;10380.584776608852;10884.238103332691;11308.321500709984;11808.291502509877;7919.683212671659;8135.593220339078;8189.79525511867;8248.381371397048;8286.295664868312;8184.918150818416;10605.946970265099;11007.889921100686;11240.875912409021;11442.656720298404 04:21:29 INFO - PROCESS | 1660 | |3;mootools.html;1568.5687184863466;1603.510929166604;1808.8010318864922;1878.0234278175349;1905.914233859476;8243.505389676624;8835.911640883589;8853.468791872492;9345.345825792198;9360.531973401328;8927.95536022319;9023.413478123917;9153.450792952412;9156.175944165025;9184.357095003352;2145.8197991104907;2418.8669623170717;2474.6288056791445;2483.863387513686;2497.275790020901;2454.1410506322823;2471.8393304435153;2477.5044991001782;2478.4175718706088;2479.442125521752;2099.4856260216193;2477.1949116537107;2500.58740307849;2506.348349429143;2508.3352911478473;2464.090329994251;2469.3949982254285;2477.170148000422;2477.900883964648;2483.3170878008514;1827.378691244983;2406.109739396417;2468.605023196298;2468.605023196298;2472.7527247275307;9070.183683468453;9120.315976301737;9123;9138.223251023604;9153.221976132038;5474.343078830526;5604.467575580314;5651.689157096358;5653.1520271959125;5663.575231857627;3076.8307743074124;3206.58314419124;3572.713823023709;3721.8325175366904;3732.533433320835;1016.5933626549365;1053.8629978102797;1058.6241884131177;1063.4044934836515;1069.9946500267527;12133;12303.384830758516;12409.255444673257;12428.316442595653;12463.75072498545;4026.8389264429093;4455.863754742585;4535.365048893158;4762.095201911626;4766.261229509431;9302.16280534755;9615.56729947154;9618.422894626345;9623.759406014766;9632.181264592587;5372.704501252469;5412.377576578665;5495.422980586981;5504.064309067388;5508.944910550923;4564.657650676212;4917.950820491821;5075.670081444769;5091.592672586185;5106;3914.197589494147;3967.5238971323624;3985.282649123186;4034.9394759078637;4130.710850240514;1232;1246.4204145072586;1248.5130798988403;1255.8995280377549;1258.2387655468492;876.4084243135858;891.5586784541564;903.2051794420868;930.8277968575835;930.9255259579218;5947.881042379215;6063.727132279058;6098.451139397475;6149.907751383733;6156.076588511759;456.3610944677478;465.1139579101816;480.1645137460863;484.69706433479075;488.0531768369352;4051.2910240707756;4066.6136717011836;4091.3453847384276;4121.732087414369;4165.45849039629;8757.91242087571;11149.16381271408;11171.720706982227;11290.604828830952;11310.660680179608;11103.389313587668;11168.832467512993;11195.104391648649;11206.775864482664;11245.269057511236;8135.267825895698;8476.533790641573;8484.32125429964;8581.227689507974;8601.182887625666;6134.693265336805;6156.384361563808;6158.846028849314;6184.072389141665;6241.21984751906;9817.509124543602;9997.950010249902;10016.69949901475;10043.849342259726;10049;10235.539400727133;10268.383896966207;10509.211809114195;10570.630027948984;10606.522706478381;7096.8935465967015;7643.808904777203;7780.766577002699;7917.247861453153;7935.484193527403;8727;8752.081031491856;8754.21212090915;8791.472511649321;8809.119088091396;7550.471466997257;7702.614869256403;7769.339606133316;7793.454458187873;7841.882371764314;7498.58757768328;7579.848403032019;7644.503107298008;7755.457118001911;7785;7233.240509746612;7284.417246620152;7318.3413492783775;7323.523970942085;7330.853382932418;4069.7965101744203;4755.167845627071;4788.186008378653;4918.483559226373;5059.519345662156;5045.167547354646;5080.695158290514;5103.336566246364;5110.310108135354;5120.590352771695 04:21:29 INFO - PROCESS | 1660 | |4;prototype.html;292.8301585080653;316.7925009119027;323.8186615495322;323.96598357172496;329.52219282041045;4733.721895088328;4793.400824896888;5010.897602527447;5346.155576326161;5605.1592261160795;4356.085222103362;4605.723656580599;4795.760211989404;5091.872703182413;5604.355499117602;3810.771353718772;4009.4186342980324;4078.3474644056955;4085.754854708727;4250.4899412070445;3810.428435734634;4178.247915375231;4183.790810459464;4204.348326009474;4266.360045993095;4140.585941405865;4203.180379825935;4212.831486740527;4223.429836972;4225.964638663517;3221.710046095839;3783.6973042156837;4045.474088368508;4224.809883555247;4249.811347156217;2999.805012674169;3353.1103919453562;3383.5263063171174;3645.6172101929446;3666.816659167031;4610.746408947506;4836.322914791932;5290.153575427951;5331.1873962852815;5377.327834020747;4293.656507479394;4604.431565536703;4652.162610730067;4880.951190488086;4885.169521181408;4863.805447782084;5113.874947511542;5477.534409575191;5810.883782324372;5839.270091238595;3837.501124853751;4002.9199416011807;4047.433359329696;4066.146109317047;4075.5313138988804;5112.539871411554;5136.743162841843;5316.680999140064;5831.212786273841;5948.256467941507;3550.591681956608;4092.447519584818;4105.542532401015;4128.318827393507;4132.318167502389;3847.5190601174854;3862.575116737157;4191.517975432804;4194.385161712701;4194.790260486963;4590.701604395704;4647.512011238839;4789.808407663724;4793.209120495149;4878.82924097662;5090.745462726923;5120.618912205768;5219.582433405319;5858.941410585925;5891.1457838614015;1279.2516377918835;1292.9676758081123;1325.8475275343267;1339.9682244671744;1382.8962827787957;852.552409984763;876.640577363278;896.9910300897038;909.5452273863068;951.6431338248157;4985.152524070844;4989.902221511262;5344.839654810362;5436.891262174734;5437.619366644376;5045.344105266292;5046.697198168048;5348.518633322942;5952.672603006876;6020.608660437145;4154.815877474905;4754.595859351924;4892.461829198785;5168.767405466763;5182.32629758129;2938.617979662673;2989.5814585957983;3061.892833750808;3152.574402455685;3178.380215857932;5065.594752419797;5526.590719366536;5717.828465145969;5936.7328470218945;6171.9691401542705;5848.637033705636;5938.61399009063;6175.752969881154;6177.227846519185;6200.689965501707;5483.835484935458;5559.971405290034;5613.8596535086135;6212.875742485125;6245.5628106032145;5334.706591137525;5781.147914233147;5823.79616713413;5918.526517878646;6014;5050.646454748133;5886.58793884424;5914.674692891932;5944.881102377842;5983.307511619875;5537.916931246115;5654.830355089354;5810.605454690759;6198.783042593487;6261.843453913689;3587.6232995535092;3827.392495151987;3938.5470670873215;4070.4911886014247;4141.91716165681;5245.898361344008;5658.830235092953;5969.432903874125;5979.551533635082;5983.696276933325;4972.707096155098;5363.256941493952;5594.1608758686525;5898.380670029756;5922.822315330547;5159.561437277874;5788.160716696139;5809.11203858756;5828.504577110993;5829.2893804381165;4792;5037.672551284155;5084.6440749147205;5451.827857010761;5517.200005999028;4658.37111989877;4919.655624106278;5891.49922256613;5912;5943.673097979653;5797.913031304618;5815.912761308668;5840.1531777892815;5849.064149736022;5904.638144742137 04:21:29 INFO - PROCESS | 1660 | |5;yui.html;645.7094307561597;675.1526833823557;683.7743544630273;728.6519938114488;742.5136535569204;15025.549233522985;15412.30644620992;15577.532674019769;15693.764593531077;15715.764263536043;14356.712865742706;14483.986120971536;14499.92750036251;14552.781708274384;14554.563363099098;692.1866806502364;873.5370253765515;894.1684233662675;921.1893533690343;921.2906062332;885.4465958775766;897.2642433204775;907.718607231757;912.0651332384326;917.4953775423523;966.7631430299587;982.5087456271864;985.3939827006375;987.1313244344966;987.9851802222972;938.9061093890556;947.5830634520789;970.4759429907876;970.898055704148;973.7711637765119;723.9782806515813;725.408791834653;728.0171768113058;729.8321386081232;732.8607564562716;14500.927495362455;14671.55985320442;14781;14871.70256594862;14907.403703851833;12596.37018149098;12665.556705515262;12788.040896932675;12799.976001919824;13011.934940325333;9695.70912872615;10087.949560252226;10130.240231982558;10244.846327305097;10368.585256589728;3077.753509828523;3247.171971147366;3476.7566270361085;3505.4216054351;3564.1624218308675;70856.58286834235;86591;88290.55854720749;88669.22661546797;89397.65903511453;9505.239580833517;9527.237820974306;9609;9813.705588832347;9879.506024698736;19299.228030878607;22567.88716056442;22615.773842261697;22633.4341641457;22697;6435.839104022437;7338.853222935618;7353.8161545961775;7544.547327160388;7695.807604809813;3896.8636097736444;3965.6232657897453;3965.722399432012;3969.0275882408996;3989.840406383712;3581.7313701472494;3613.7109031277437;3618.674319311222;3671.2290419011774;3688.243909998444;619.7180282971327;630.130420020369;633.4267487923437;633.4963703855447;639.3127388057763;472.2538389344827;539.6815878631547;551.2723205368875;571.6484362117268;599.3946114424463;7606.682729644123;7792.376609871197;7797.454178207585;7960.681572737026;8021.919780802234;498.3969397029635;513.7585334892594;520.456123351099;523.9423663397023;545.9454054594509;3621.6559426854406;3664.4136938089778;3752.793596352227;3807.238552289498;3850.5956874528333;99697.50151249343;102819.97180028104;103129;104467;105529;67427.66286168636;83116.16883831084;83118;85162.57418712867;86787.56606217055;73771.63114184394;74813.50372992619;77794;79499.60250198828;79962.20037799663;7209.243029481829;7226.349628533457;7299.598522081336;7443.330100291;7615.238476152562;45432.545674544155;45522.77238613918;46622.53377466318;47231.763841180575;48728.75635621799;65350.366240844356;66140.66929665321;68775.96836047363;68953;70030.64984675044;10236.232282578687;10260.486975651336;10323.63867264642;10454.42500662471;10618.05548622825;11177.66467005991;11196.384198868815;11329.263597866113;11546.076313895037;11707.707307317385;9256.935452423058;10393.844092338772;10648.148148147975;10756.193285503776;10792.406417647106;9527.761805954906;10144;10367;10384.532696028848;10387.065164134961;10361.274710770174;10380.27338086357;10406.895931040593;10694.251402401756;10705.785884282426;8869.822603548022;8931.508767017876;9260.907390926004;9319.720408387759;9501.524923753646;8779.956100219459;8780.429272097294;9388.497840345512;9448.149666530046;9486.292467355666 04:21:29 INFO - PROCESS | 1660 | __end_tp_report 04:21:29 INFO - PROCESS | 1660 | __start_cc_report 04:21:29 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,418 04:21:29 INFO - PROCESS | 1660 | __end_cc_report 04:21:29 INFO - PROCESS | 1660 | __startTimestamp1463656889765__endTimestamp 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:21:29 INFO - PROCESS | 1660 | Number of tests: 6 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5765.78 Median:6055.46 stddev:802.06 (13.2%) stddev-sans-first:765.46 04:21:29 INFO - PROCESS | 1660 | Values: 2613.8 3471.5 3499.9 3520.1 3529.8 5773.4 5808.0 5925.9 5939.7 5972.2 4835.3 5096.9 5872.6 5915.6 5976.0 5011.6 5287.8 5365.8 5371.1 5432.5 434.3 5231.5 5299.6 5309.8 5331.5 5209.4 5300.3 5325.7 5359.9 5372.2 5353.5 5377.0 5386.6 5392.1 5417.7 4382.6 5183.7 5258.6 5301.3 5329.9 5582.6 5724.5 5734.4 5790.6 5808.1 6191.3 6246.2 6288.1 6322.7 6325.3 6202.7 6264.1 6271.8 6328.0 6346.2 6034.7 6073.8 6080.1 6088.6 6097.0 4950.3 5907.0 6365.4 6397.1 6399.5 5160.8 6207.9 6223.8 6259.7 6260.1 6209.2 6289.7 6318.1 6343.7 6362.8 5103.0 6157.5 6272.0 6307.2 6324.6 6226.8 6258.4 6278.8 6294.4 6297.6 5102.0 5110.9 5123.0 5130.6 5148.7 4447.1 4451.5 4488.1 4529.9 4596.5 5939.2 5951.3 6054.5 6055.5 6081.6 6296.7 6342.6 6353.3 6356.6 6361.3 5840.4 5933.6 5966.2 6013.5 6040.2 5512.8 5536.8 5547.7 5552.4 5556.9 5392.3 6537.6 6549.2 6562.1 6575.2 5214.8 6221.3 6268.8 6284.9 6338.9 6555.2 6559.8 6560.9 6573.1 6577.6 6195.4 6302.4 6306.1 6314.2 6335.7 4686.6 5750.6 6263.9 6284.9 6345.8 6260.3 6279.2 6281.8 6293.5 6321.1 6075.9 6152.1 6197.9 6206.8 6226.1 5204.7 5473.7 5535.8 6122.4 6125.1 5996.6 6290.4 6298.3 6305.2 6318.5 6034.9 6132.8 6142.5 6156.8 6190.5 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:61516.54 Median:37136.32 stddev:133003.00 (358.1%) stddev-sans-first:133297.24 04:21:29 INFO - PROCESS | 1660 | Values: 511.6 516.7 521.5 536.7 596.6 39205.4 42326.0 42327.2 42404.6 42419.6 37703.1 42993.8 43611.7 43672.0 43888.0 9585.2 9729.0 9735.9 9804.7 9845.4 8307.5 9608.8 9662.3 9671.8 9774.2 9712.7 9718.7 9748.0 9790.7 9799.6 9736.3 9753.7 9778.7 9794.2 9801.4 9712.6 9728.8 9744.6 9820.0 9827.6 40599.8 40636.0 40981.2 41025.4 41039.8 2877.9 3106.3 3156.9 3416.6 3456.1 27397.5 28367.0 28472.0 29060.9 29165.8 2649.3 2687.2 2726.9 2782.4 2797.5 385511.0 386727.0 387079.0 389295.0 391422.0 29449.9 34520.7 35250.8 35498.0 35624.8 28462.3 35112.8 36337.3 36472.1 36499.4 14919.9 15189.9 15192.2 15287.9 15364.2 12505.6 12525.4 12957.9 12967.0 13001.5 7962.5 8048.4 8141.4 8183.4 8227.8 2746.4 2852.2 3377.3 3428.4 3438.5 834.7 847.8 987.8 1060.8 1066.6 28994.6 29256.3 29414.0 30134.0 30553.7 1114.2 1118.6 1134.4 1144.0 1170.4 15306.2 15320.8 15458.4 15607.8 15705.4 515273.0 559841.4 592677.0 595761.0 596962.0 57993.1 58061.7 58815.4 58933.1 58939.0 1479.6 1484.5 1484.5 1487.3 1498.5 29077.0 29241.6 29338.7 29849.0 29903.0 50665.5 51579.5 52109.0 52137.0 52199.2 508396.0 508728.0 510123.0 521422.0 525006.4 25536.5 25723.7 25779.1 26364.1 26693.9 44400.0 44734.1 45229.3 45351.5 45363.3 29586.6 36431.8 36622.5 36964.6 37308.0 31706.5 37334.4 37368.3 38094.2 38234.2 32376.0 36092.5 36611.8 36867.0 38714.2 25748.7 32734.0 33276.5 33368.2 34002.0 20114.5 24019.2 24744.1 25633.2 25744.4 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34393.99 Median:21545.24 stddev:96165.39 (446.3%) stddev-sans-first:96401.18 04:21:29 INFO - PROCESS | 1660 | Values: 347.6 389.4 399.6 407.9 414.3 19152.0 21063.8 21339.7 21446.0 21462.7 17775.9 20555.2 21343.9 21627.8 21668.0 5333.6 5969.0 6163.4 6165.2 6200.0 5914.5 6147.3 6193.6 6207.6 6209.0 5938.1 6074.0 6100.0 6110.7 6111.0 5578.3 5983.8 6088.0 6103.5 6118.9 5639.4 5796.5 5799.5 5810.4 5828.0 13545.3 13688.6 13853.6 13858.7 13904.6 1798.0 1978.4 2213.1 2262.4 2273.0 9835.8 9848.6 9933.2 10098.2 10098.9 1740.1 1810.9 1815.6 1819.4 1826.5 110561.0 115608.4 116121.0 118663.0 118771.0 9448.4 11930.0 12100.7 12163.0 12393.6 15076.4 15098.3 15122.3 15128.7 15134.3 8619.0 8729.0 8841.8 8892.9 8956.3 8801.5 8832.4 8863.8 8881.6 9003.0 4596.4 4597.1 4677.5 4706.3 4709.8 1968.8 2017.2 2031.6 2037.5 2058.3 818.5 1016.7 1016.9 1020.8 1045.0 17333.5 18191.1 20521.0 20558.5 21193.6 930.9 967.8 981.8 983.6 993.7 10945.4 10997.1 11163.8 11239.8 11251.4 535395.0 537901.0 541695.0 614797.0 626469.0 132344.0 133415.0 134460.0 137752.0 140125.2 991.5 994.3 1015.7 1016.2 1057.3 19383.8 19623.8 19707.9 19708.0 19819.6 55957.7 56427.4 57344.4 57348.4 58065.7 82726.0 83288.6 83463.3 85326.6 85542.0 6621.4 8070.6 8084.2 8116.6 8255.7 10310.4 12112.3 12215.7 12306.8 12652.8 10610.3 10708.2 10790.4 10828.7 11037.1 10396.1 10650.5 10684.0 10977.1 11069.4 8122.4 10380.6 10884.2 11308.3 11808.3 7919.7 8135.6 8189.8 8248.4 8286.3 8184.9 10605.9 11007.9 11240.9 11442.7 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5851.79 Median:2703.00 stddev:3328.42 (123.1%) stddev-sans-first:3322.20 04:21:29 INFO - PROCESS | 1660 | Values: 1568.6 1603.5 1808.8 1878.0 1905.9 8243.5 8835.9 8853.5 9345.3 9360.5 8928.0 9023.4 9153.5 9156.2 9184.4 2145.8 2418.9 2474.6 2483.9 2497.3 2454.1 2471.8 2477.5 2478.4 2479.4 2099.5 2477.2 2500.6 2506.3 2508.3 2464.1 2469.4 2477.2 2477.9 2483.3 1827.4 2406.1 2468.6 2468.6 2472.8 9070.2 9120.3 9123.0 9138.2 9153.2 5474.3 5604.5 5651.7 5653.2 5663.6 3076.8 3206.6 3572.7 3721.8 3732.5 1016.6 1053.9 1058.6 1063.4 1070.0 12133.0 12303.4 12409.3 12428.3 12463.8 4026.8 4455.9 4535.4 4762.1 4766.3 9302.2 9615.6 9618.4 9623.8 9632.2 5372.7 5412.4 5495.4 5504.1 5508.9 4564.7 4918.0 5075.7 5091.6 5106.0 3914.2 3967.5 3985.3 4034.9 4130.7 1232.0 1246.4 1248.5 1255.9 1258.2 876.4 891.6 903.2 930.8 930.9 5947.9 6063.7 6098.5 6149.9 6156.1 456.4 465.1 480.2 484.7 488.1 4051.3 4066.6 4091.3 4121.7 4165.5 8757.9 11149.2 11171.7 11290.6 11310.7 11103.4 11168.8 11195.1 11206.8 11245.3 8135.3 8476.5 8484.3 8581.2 8601.2 6134.7 6156.4 6158.8 6184.1 6241.2 9817.5 9998.0 10016.7 10043.8 10049.0 10235.5 10268.4 10509.2 10570.6 10606.5 7096.9 7643.8 7780.8 7917.2 7935.5 8727.0 8752.1 8754.2 8791.5 8809.1 7550.5 7702.6 7769.3 7793.5 7841.9 7498.6 7579.8 7644.5 7755.5 7785.0 7233.2 7284.4 7318.3 7323.5 7330.9 4069.8 4755.2 4788.2 4918.5 5059.5 5045.2 5080.7 5103.3 5110.3 5120.6 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4614.33 Median:5048.67 stddev:1410.65 (27.9%) stddev-sans-first:1376.81 04:21:29 INFO - PROCESS | 1660 | Values: 292.8 316.8 323.8 324.0 329.5 4733.7 4793.4 5010.9 5346.2 5605.2 4356.1 4605.7 4795.8 5091.9 5604.4 3810.8 4009.4 4078.3 4085.8 4250.5 3810.4 4178.2 4183.8 4204.3 4266.4 4140.6 4203.2 4212.8 4223.4 4226.0 3221.7 3783.7 4045.5 4224.8 4249.8 2999.8 3353.1 3383.5 3645.6 3666.8 4610.7 4836.3 5290.2 5331.2 5377.3 4293.7 4604.4 4652.2 4881.0 4885.2 4863.8 5113.9 5477.5 5810.9 5839.3 3837.5 4002.9 4047.4 4066.1 4075.5 5112.5 5136.7 5316.7 5831.2 5948.3 3550.6 4092.4 4105.5 4128.3 4132.3 3847.5 3862.6 4191.5 4194.4 4194.8 4590.7 4647.5 4789.8 4793.2 4878.8 5090.7 5120.6 5219.6 5858.9 5891.1 1279.3 1293.0 1325.8 1340.0 1382.9 852.6 876.6 897.0 909.5 951.6 4985.2 4989.9 5344.8 5436.9 5437.6 5045.3 5046.7 5348.5 5952.7 6020.6 4154.8 4754.6 4892.5 5168.8 5182.3 2938.6 2989.6 3061.9 3152.6 3178.4 5065.6 5526.6 5717.8 5936.7 6172.0 5848.6 5938.6 6175.8 6177.2 6200.7 5483.8 5560.0 5613.9 6212.9 6245.6 5334.7 5781.1 5823.8 5918.5 6014.0 5050.6 5886.6 5914.7 5944.9 5983.3 5537.9 5654.8 5810.6 6198.8 6261.8 3587.6 3827.4 3938.5 4070.5 4141.9 5245.9 5658.8 5969.4 5979.6 5983.7 4972.7 5363.3 5594.2 5898.4 5922.8 5159.6 5788.2 5809.1 5828.5 5829.3 4792.0 5037.7 5084.6 5451.8 5517.2 4658.4 4919.7 5891.5 5912.0 5943.7 5797.9 5815.9 5840.2 5849.1 5904.6 04:21:29 INFO - PROCESS | 1660 | 04:21:29 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18753.71 Median:609.56 stddev:27492.41 (4510.2%) stddev-sans-first:27535.92 04:21:29 INFO - PROCESS | 1660 | Values: 645.7 675.2 683.8 728.7 742.5 15025.5 15412.3 15577.5 15693.8 15715.8 14356.7 14484.0 14499.9 14552.8 14554.6 692.2 873.5 894.2 921.2 921.3 885.4 897.3 907.7 912.1 917.5 966.8 982.5 985.4 987.1 988.0 938.9 947.6 970.5 970.9 973.8 724.0 725.4 728.0 729.8 732.9 14500.9 14671.6 14781.0 14871.7 14907.4 12596.4 12665.6 12788.0 12800.0 13011.9 9695.7 10087.9 10130.2 10244.8 10368.6 3077.8 3247.2 3476.8 3505.4 3564.2 70856.6 86591.0 88290.6 88669.2 89397.7 9505.2 9527.2 9609.0 9813.7 9879.5 19299.2 22567.9 22615.8 22633.4 22697.0 6435.8 7338.9 7353.8 7544.5 7695.8 3896.9 3965.6 3965.7 3969.0 3989.8 3581.7 3613.7 3618.7 3671.2 3688.2 619.7 630.1 633.4 633.5 639.3 472.3 539.7 551.3 571.6 599.4 7606.7 7792.4 7797.5 7960.7 8021.9 498.4 513.8 520.5 523.9 545.9 3621.7 3664.4 3752.8 3807.2 3850.6 99697.5 102820.0 103129.0 104467.0 105529.0 67427.7 83116.2 83118.0 85162.6 86787.6 73771.6 74813.5 77794.0 79499.6 79962.2 7209.2 7226.3 7299.6 7443.3 7615.2 45432.5 45522.8 46622.5 47231.8 48728.8 65350.4 66140.7 68776.0 68953.0 70030.6 10236.2 10260.5 10323.6 10454.4 10618.1 11177.7 11196.4 11329.3 11546.1 11707.7 9256.9 10393.8 10648.1 10756.2 10792.4 9527.8 10144.0 10367.0 10384.5 10387.1 10361.3 10380.3 10406.9 10694.3 10705.8 8869.8 8931.5 9260.9 9319.7 9501.5 8780.0 8780.4 9388.5 9448.1 9486.3 04:21:29 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:21:29 INFO - PROCESS | 1660 | 04:21:30 INFO - TEST-INFO | 1660: exit 0 04:21:30 INFO - TEST-OK | dromaeo_css | took 1112126ms 04:21:30 INFO - TEST-START | kraken 04:21:30 INFO - operating with platform_type : mac_ 04:21:30 INFO - Initialising browser for kraken test... 04:21:30 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpj6ISx6/profile http://localhost:49276/getInfo.html) 04:21:33 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 04:21:33 INFO - PROCESS | 1672 | colorDepth:24 04:21:33 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 04:21:33 INFO - PROCESS | 1672 | __metrics 04:21:33 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 04:21:33 INFO - TEST-INFO | 1672: exit 0 04:21:33 INFO - Browser initialized. 04:21:33 INFO - Running cycle 1/1 for kraken test... 04:21:33 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpj6ISx6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:21:46 INFO - PROCESS | 1675 | RSS: Main: 249475072 04:21:46 INFO - PROCESS | 1675 | 04:21:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 04:21:49 INFO - PROCESS | 1675 | RSS: Main: 261218304 04:21:49 INFO - PROCESS | 1675 | 04:21:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 04:21:53 INFO - PROCESS | 1675 | RSS: Main: 265879552 04:21:53 INFO - PROCESS | 1675 | 04:21:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 04:21:57 INFO - PROCESS | 1675 | RSS: Main: 266739712 04:21:57 INFO - PROCESS | 1675 | 04:21:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 04:22:00 INFO - PROCESS | 1675 | RSS: Main: 267464704 04:22:00 INFO - PROCESS | 1675 | 04:22:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:22:02 INFO - PROCESS | 1675 | RSS: Main: 267517952 04:22:02 INFO - PROCESS | 1675 | 04:22:05 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 04:22:06 INFO - PROCESS | 1675 | RSS: Main: 267956224 04:22:06 INFO - PROCESS | 1675 | 04:22:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 04:22:10 INFO - PROCESS | 1675 | RSS: Main: 268402688 04:22:10 INFO - PROCESS | 1675 | 04:22:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 04:22:14 INFO - PROCESS | 1675 | RSS: Main: 268136448 04:22:14 INFO - PROCESS | 1675 | 04:22:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:22:16 INFO - PROCESS | 1675 | RSS: Main: 267780096 04:22:16 INFO - PROCESS | 1675 | 04:22:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:22:19 INFO - PROCESS | 1675 | RSS: Main: 268255232 04:22:19 INFO - PROCESS | 1675 | 04:22:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:22:22 INFO - PROCESS | 1675 | RSS: Main: 267833344 04:22:22 INFO - PROCESS | 1675 | 04:22:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:22:25 INFO - PROCESS | 1675 | RSS: Main: 267898880 04:22:25 INFO - PROCESS | 1675 | 04:22:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:22:29 INFO - PROCESS | 1675 | RSS: Main: 267849728 04:22:29 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 04:22:31 INFO - PROCESS | 1675 | RSS: Main: 270299136 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | __start_tp_report 04:22:31 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 04:22:31 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 04:22:31 INFO - PROCESS | 1675 | |i|pagename|runs| 04:22:31 INFO - PROCESS | 1675 | |0;ai-astar;116;116;110;123;119;95;99;119;95;109 04:22:31 INFO - PROCESS | 1675 | |1;audio-beat-detection;151;104;130;210;121;132;134;146;182;168 04:22:31 INFO - PROCESS | 1675 | |2;audio-dft;202;208;266;243;194;198;195;201;198;197 04:22:31 INFO - PROCESS | 1675 | |3;audio-fft;69;80;75;174;57;57;63;69;136;69 04:22:31 INFO - PROCESS | 1675 | |4;audio-oscillator;68;71;173;66;105;121;160;100;80;122 04:22:31 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;87;88;88;89;86;90;88;137;87 04:22:31 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;96;123;98;94;95;95;120;138;95 04:22:31 INFO - PROCESS | 1675 | |7;imaging-desaturate;106;143;92;95;125;148;90;91;92;90 04:22:31 INFO - PROCESS | 1675 | |8;json-parse-financial;57;59;59;58;58;58;51;130;95;88 04:22:31 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;66;68;66;71;71;71;71;65;66;69 04:22:31 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;87;94;75;81;105;107;108;98;88;87 04:22:31 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;142;158;147;137;135;140;149;156;235;174 04:22:31 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;205;163;170;221;224;173;220;167;223;203 04:22:31 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;64;65;63;68;64;78;86;61;56;82 04:22:31 INFO - PROCESS | 1675 | __end_tp_report 04:22:31 INFO - PROCESS | 1675 | __start_cc_report 04:22:31 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1088 04:22:31 INFO - PROCESS | 1675 | __end_cc_report 04:22:31 INFO - PROCESS | 1675 | __startTimestamp1463656951613__endTimestamp 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | ------- Summary: start ------- 04:22:31 INFO - PROCESS | 1675 | Number of tests: 14 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:110.10 Median:121.00 stddev:10.41 (8.6%) stddev-sans-first:10.82 04:22:31 INFO - PROCESS | 1675 | Values: 116.0 116.0 110.0 123.0 119.0 95.0 99.0 119.0 95.0 109.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:148.50 stddev:31.36 (21.1%) stddev-sans-first:33.25 04:22:31 INFO - PROCESS | 1675 | Values: 151.0 104.0 130.0 210.0 121.0 132.0 134.0 146.0 182.0 168.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:210.20 Median:201.50 stddev:24.29 (12.1%) stddev-sans-first:25.59 04:22:31 INFO - PROCESS | 1675 | Values: 202.0 208.0 266.0 243.0 194.0 198.0 195.0 201.0 198.0 197.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:84.90 Median:69.00 stddev:38.68 (56.1%) stddev-sans-first:40.60 04:22:31 INFO - PROCESS | 1675 | Values: 69.0 80.0 75.0 174.0 57.0 57.0 63.0 69.0 136.0 69.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:106.60 Median:119.50 stddev:37.81 (31.6%) stddev-sans-first:37.43 04:22:31 INFO - PROCESS | 1675 | Values: 68.0 71.0 173.0 66.0 105.0 121.0 160.0 100.0 80.0 122.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.00 stddev:15.54 (17.7%) stddev-sans-first:16.42 04:22:31 INFO - PROCESS | 1675 | Values: 89.0 87.0 88.0 88.0 89.0 86.0 90.0 88.0 137.0 87.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:95.00 stddev:16.02 (16.9%) stddev-sans-first:16.51 04:22:31 INFO - PROCESS | 1675 | Values: 94.0 96.0 123.0 98.0 94.0 95.0 95.0 120.0 138.0 95.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:90.50 stddev:22.92 (25.3%) stddev-sans-first:24.31 04:22:31 INFO - PROCESS | 1675 | Values: 106.0 143.0 92.0 95.0 125.0 148.0 90.0 91.0 92.0 90.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:71.30 Median:58.50 stddev:25.25 (43.2%) stddev-sans-first:26.24 04:22:31 INFO - PROCESS | 1675 | Values: 57.0 59.0 59.0 58.0 58.0 58.0 51.0 130.0 95.0 88.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:70.00 stddev:2.50 (3.6%) stddev-sans-first:2.50 04:22:31 INFO - PROCESS | 1675 | Values: 66.0 68.0 66.0 71.0 71.0 71.0 71.0 65.0 66.0 69.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:87.00 stddev:11.33 (13.0%) stddev-sans-first:11.81 04:22:31 INFO - PROCESS | 1675 | Values: 87.0 94.0 75.0 81.0 105.0 107.0 108.0 98.0 88.0 87.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:152.50 stddev:29.69 (19.5%) stddev-sans-first:30.98 04:22:31 INFO - PROCESS | 1675 | Values: 142.0 158.0 147.0 137.0 135.0 140.0 149.0 156.0 235.0 174.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.90 Median:212.50 stddev:25.76 (12.1%) stddev-sans-first:27.15 04:22:31 INFO - PROCESS | 1675 | Values: 205.0 163.0 170.0 221.0 224.0 173.0 220.0 167.0 223.0 203.0 04:22:31 INFO - PROCESS | 1675 | 04:22:31 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:66.50 stddev:9.86 (14.8%) stddev-sans-first:10.31 04:22:31 INFO - PROCESS | 1675 | Values: 64.0 65.0 63.0 68.0 64.0 78.0 86.0 61.0 56.0 82.0 04:22:31 INFO - PROCESS | 1675 | -------- Summary: end -------- 04:22:31 INFO - PROCESS | 1675 | 04:22:32 INFO - TEST-INFO | 1675: exit 0 04:22:32 INFO - TEST-OK | kraken | took 62252ms 04:22:32 INFO - SUITE-END | took 1174s 04:22:32 INFO - Completed test suite (00:19:34) 04:22:32 INFO - javascript benchmark 04:22:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7093.401608993917, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2613.803964702647, 3471.5486986691726, 3499.9125021874434, 3520.0671821967167, 3529.8058606776617, 5773.42265773423, 5807.970960145204, 5925.85185370366, 5939.673317967516, 5972.223610930584, 4835.347228124202, 5096.898062038757, 5872.559558033143, 5915.615484993473, 5976.0, 5011.574016208627, 5287.762050707727, 5365.812196573121, 5371.086915224421, 5432.483914028161, 434.2914086081933, 5231.476852314776, 5299.629025968184, 5309.787608495675, 5331.466853314657, 5209.400918894319, 5300.310959575249, 5325.733713314319, 5359.892802143935, 5372.167314066326, 5353.518183363516, 5377.0, 5386.596005299618, 5392.05639013175, 5417.674939503642, 4382.649388048949, 5183.714895680736, 5258.605604579634, 5301.257823904656, 5329.866753331159, 5582.609217354746, 5724.513416359573, 5734.369219385864, 5790.565707571906, 5808.099744539603, 6191.25704915413, 6246.187995560548, 6288.119663247149, 6322.747090116436, 6325.272593651697, 6202.658853763041, 6264.091706702503, 6271.843203919893, 6328.0, 6346.238451385863, 6034.698265086772, 6073.757049718006, 6080.057591073347, 6088.634681919099, 6096.993995990656, 4950.25746138075, 5906.995810712189, 6365.427111559982, 6397.136386587844, 6399.488040956712, 5160.819371321985, 6207.906881396782, 6223.813285601439, 6259.7496100156395, 6260.060990851318, 6209.223847019122, 6289.7484100636375, 6318.147050148263, 6343.651099189497, 6362.840928976812, 5103.0, 6157.476614487819, 6272.0, 6307.211598550181, 6324.588901721466, 6226.844328891814, 6258.405451482102, 6278.811635650938, 6294.433500984934, 6297.559170857996, 5102.030614183293, 5110.897782044338, 5123.001014802077, 5130.615203859726, 5148.734556794998, 4447.08834688888, 4451.465824101128, 4488.147252022105, 4529.932051019237, 4596.540345965377, 5939.168516407706, 5951.345352011275, 6054.545909056838, 6055.455009049118, 6081.604695694766, 6296.710762959136, 6342.619442833445, 6353.30113687494, 6356.555041147075, 6361.332060133712, 5840.3575606683235, 5933.554983376264, 5966.224390829251, 6013.51891848651, 6040.244969378828, 5512.80705175317, 5536.833894983077, 5547.667139971614, 5552.3892371839065, 5556.861078473071, 5392.299001129828, 6537.640429776313, 6549.181352330958, 6562.081308616797, 6575.243846957565, 5214.817481388133, 6221.253449585897, 6268.843278918063, 6284.874302514016, 6338.904916426165, 6555.246146693191, 6559.770408035696, 6560.901586476302, 6573.1454910861285, 6577.6053436793945, 6195.411435913581, 6302.369763023844, 6306.085617585516, 6314.210723659543, 6335.683215839097, 4686.554777296152, 5750.562359410147, 6263.906041409474, 6284.874302514016, 6345.777897773556, 6260.280067792262, 6279.183706118175, 6281.811545653638, 6293.496520278275, 6321.083442900662, 6075.939240607714, 6152.138700582012, 6197.907031394442, 6206.751729930933, 6226.128342032029, 5204.724842413481, 5473.67157970523, 5535.833924982257, 6122.387761223842, 6125.081237814364, 5996.610220335664, 6290.433860952537, 6298.275698294571, 6305.243370795534, 6318.462930650946, 6034.879302414015, 6132.814448934826, 6142.477889379274, 6156.784512542219, 6190.473809726223], "value": 5718.189834540142, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.57283668137103, 516.6796586116606, 521.4993606138107, 536.656539814519, 596.5972968246433, 39205.41191882124, 42326.0, 42327.15345693088, 42404.57595424045, 42419.57580424195, 37703.05742356442, 42993.785031074876, 43611.6916492505, 43672.0, 43888.0, 9585.233181345493, 9728.951355243214, 9735.902640973605, 9804.725385699849, 9845.36005159666, 8307.501549907025, 9608.80782384352, 9662.275329350292, 9671.758206044835, 9774.218062554979, 9712.66005689801, 9718.70843874685, 9747.951260243688, 9790.70627881161, 9799.559019844124, 9736.318457708, 9753.707388778348, 9778.706638800848, 9794.16749576287, 9801.411915285105, 9712.61149554017, 9728.805423891483, 9744.61021559144, 9820.0, 9827.606895724162, 40599.797001014806, 40635.98410039743, 40981.1803763926, 41025.38461923073, 41039.7948010261, 2877.8848846046135, 3106.2544989202656, 3156.921076973071, 3416.5900091988874, 3456.1359660084977, 27397.45205095887, 28367.0, 28472.0, 29060.854695726386, 29165.83336666531, 2649.284693132843, 2687.153546632824, 2726.8772905219116, 2782.3600571868583, 2797.5104356737693, 385511.0, 386727.0, 387079.0, 389295.0, 391421.99999999715, 29449.85275073611, 34520.654793452246, 35250.823745881105, 35498.0, 35624.821875890455, 28462.288442789097, 35112.82443587766, 36337.27325453476, 36472.08819779527, 36499.35006499382, 14919.850801491846, 15189.924050379677, 15192.240387980777, 15287.92984491075, 15364.231788410534, 12505.562305319452, 12525.436355363849, 12957.870421295855, 12967.0, 13001.544945927037, 7962.482438641586, 8048.4366094374, 8141.430099893069, 8183.427160098736, 8227.753167404988, 2746.368335282856, 2852.217364147408, 3377.2907689385015, 3428.3828910795796, 3438.484227365865, 834.6661335465741, 847.7583888591845, 987.7678745494803, 1060.7825395793843, 1066.594694016269, 28994.565081523793, 29256.26859328534, 29414.0, 30134.0, 30553.694463055086, 1114.2144787924494, 1118.552578968419, 1134.432783608196, 1143.9942800285946, 1170.39724541861, 15306.234688265542, 15320.770188447183, 15458.420576579882, 15607.765883511529, 15705.371785128695, 515273.0, 559841.4015859871, 592677.0, 595761.0211948913, 596962.0, 57993.13010304849, 58061.70969145127, 58815.41184588185, 58933.11600325999, 58939.0, 1479.6448852275375, 1484.5397926642775, 1484.547213100015, 1487.263804416799, 1498.460554200487, 29077.0, 29241.561376578942, 29338.706612934453, 29849.0, 29902.953396631012, 50665.49334506755, 51579.48420515747, 52109.0, 52137.0, 52199.21701174409, 508396.0, 508728.0, 510123.0, 521422.0, 525006.3747131312, 25536.48927021412, 25723.742762572136, 25779.097731579306, 26364.07725729667, 26693.866530667998, 44400.0, 44734.10531789411, 45229.321560175966, 45351.546484536055, 45363.31955020611, 29586.556201656564, 36431.81784091169, 36622.450663240605, 36964.63035369612, 37308.0, 31706.524402133524, 37334.43998339973, 37368.25263494769, 38094.23811523809, 38234.23531529298, 32376.0, 36092.458613120296, 36611.81694091512, 36867.0, 38714.22571548609, 25748.742512575383, 32734.0, 33276.500852486744, 33368.23839157488, 34002.0, 20114.497137571678, 24019.159329423386, 24744.133955311478, 25633.231003069937, 25744.356391090372], "value": 18000.403172070568, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.5707501235974, 389.43337444018937, 399.5584878709026, 407.9306517891958, 414.3039693315231, 19152.0, 21063.788302228277, 21339.67990480144, 21446.0, 21462.678059829097, 17775.911120444413, 20555.177792888306, 21343.893280533615, 21627.81047042416, 21668.0, 5333.626646134772, 5968.999999999989, 6163.383661633823, 6165.198524191849, 6200.0, 5914.467697907187, 6147.293061297941, 6193.566450348478, 6207.56547041705, 6209.037599172136, 5938.079597662348, 6073.969630151865, 6100.0, 6110.694465276741, 6111.0, 5578.30271216098, 5983.820485385445, 6087.969560152215, 6103.542234332421, 6118.877622447549, 5639.379668236491, 5796.478316951495, 5799.536037117021, 5810.360860305363, 5828.0, 13545.255010974393, 13688.589342319747, 13853.584392468241, 13858.722825543433, 13904.58286251414, 1797.9640407191782, 1978.357033963953, 2213.1368766181204, 2262.4343914021497, 2273.0453209651987, 9835.803283934281, 9848.556814943273, 9933.155681767059, 10098.203353407147, 10098.949505252427, 1740.1125426032688, 1810.94567162984, 1815.582416044304, 1819.417786308382, 1826.4794533558006, 110561.0, 115608.42195789052, 116121.0, 118663.0, 118771.0, 9448.385854919408, 11929.985951194245, 12100.69748256283, 12163.0, 12393.566225182076, 15076.396944122112, 15098.320575574126, 15122.319495622723, 15128.697426051418, 15134.318955647022, 8618.956905215433, 8728.956355218183, 8841.778955526162, 8892.8666070009, 8956.283497320199, 8801.51591662452, 8832.425892316978, 8863.822723545492, 8881.600327985256, 9002.954985225162, 4596.448426188812, 4597.126545956257, 4677.462091859412, 4706.294055891575, 4709.764511774398, 1968.8326492248034, 2017.1729590867967, 2031.5733695923725, 2037.5008123009661, 2058.3310424112224, 818.5088946631973, 1016.6797458800454, 1016.9491525423847, 1020.8056573808661, 1045.0490054050929, 17333.4799955999, 18191.090445477672, 20520.973951302374, 20558.486037849172, 21193.576128477343, 930.8689941720706, 967.8209531236603, 981.7741919358444, 983.6409710455632, 993.6919554937992, 10945.398003109745, 10997.094303944807, 11163.83254251187, 11239.775204495865, 11251.381174035343, 535395.0, 537901.0, 541695.0, 614797.0, 626469.0, 132344.0, 133415.0, 134460.0, 137752.0, 140125.19749605155, 991.4596544883057, 994.2990191914682, 1015.7257540464034, 1016.1819735113157, 1057.3074636113204, 19383.83696978186, 19623.80376196277, 19707.90146049318, 19708.0, 19819.60360792747, 55957.720211398686, 56427.43572564386, 57344.42655573391, 57348.42651573598, 58065.709671451375, 82726.0, 83288.58355708425, 83463.3307333862, 85326.57336713276, 85542.0, 6621.370969757865, 8070.5964701763505, 8084.231997960184, 8116.553589552395, 8255.711050113217, 10310.43292618913, 12112.273263604213, 12215.69460763488, 12306.815397768862, 12652.810207846705, 10610.310329828537, 10708.196885233483, 10790.4255372078, 10828.729281768019, 11037.06184974254, 10396.064354208158, 10650.520726567169, 10684.038436540748, 10977.12183025372, 11069.391183484986, 8122.431429799857, 10380.584776608852, 10884.238103332691, 11308.321500709984, 11808.291502509877, 7919.683212671659, 8135.593220339078, 8189.79525511867, 8248.381371397048, 8286.295664868312, 8184.918150818416, 10605.946970265099, 11007.889921100686, 11240.875912409021, 11442.656720298404], "value": 9489.973892771375, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.5687184863466, 1603.510929166604, 1808.8010318864922, 1878.0234278175349, 1905.914233859476, 8243.505389676624, 8835.911640883589, 8853.468791872492, 9345.345825792198, 9360.531973401328, 8927.95536022319, 9023.413478123917, 9153.450792952412, 9156.175944165025, 9184.357095003352, 2145.8197991104907, 2418.8669623170717, 2474.6288056791445, 2483.863387513686, 2497.275790020901, 2454.1410506322823, 2471.8393304435153, 2477.5044991001782, 2478.4175718706088, 2479.442125521752, 2099.4856260216193, 2477.1949116537107, 2500.58740307849, 2506.348349429143, 2508.3352911478473, 2464.090329994251, 2469.3949982254285, 2477.170148000422, 2477.900883964648, 2483.3170878008514, 1827.378691244983, 2406.109739396417, 2468.605023196298, 2468.605023196298, 2472.7527247275307, 9070.183683468453, 9120.315976301737, 9123.0, 9138.223251023604, 9153.221976132038, 5474.343078830526, 5604.467575580314, 5651.689157096358, 5653.1520271959125, 5663.575231857627, 3076.8307743074124, 3206.58314419124, 3572.713823023709, 3721.8325175366904, 3732.533433320835, 1016.5933626549365, 1053.8629978102797, 1058.6241884131177, 1063.4044934836515, 1069.9946500267527, 12133.0, 12303.384830758516, 12409.255444673257, 12428.316442595653, 12463.75072498545, 4026.8389264429093, 4455.863754742585, 4535.365048893158, 4762.095201911626, 4766.261229509431, 9302.16280534755, 9615.56729947154, 9618.422894626345, 9623.759406014766, 9632.181264592587, 5372.704501252469, 5412.377576578665, 5495.422980586981, 5504.064309067388, 5508.944910550923, 4564.657650676212, 4917.950820491821, 5075.670081444769, 5091.592672586185, 5106.0, 3914.197589494147, 3967.5238971323624, 3985.282649123186, 4034.9394759078637, 4130.710850240514, 1232.0, 1246.4204145072586, 1248.5130798988403, 1255.8995280377549, 1258.2387655468492, 876.4084243135858, 891.5586784541564, 903.2051794420868, 930.8277968575835, 930.9255259579218, 5947.881042379215, 6063.727132279058, 6098.451139397475, 6149.907751383733, 6156.076588511759, 456.3610944677478, 465.1139579101816, 480.1645137460863, 484.69706433479075, 488.0531768369352, 4051.2910240707756, 4066.6136717011836, 4091.3453847384276, 4121.732087414369, 4165.45849039629, 8757.91242087571, 11149.16381271408, 11171.720706982227, 11290.604828830952, 11310.660680179608, 11103.389313587668, 11168.832467512993, 11195.104391648649, 11206.775864482664, 11245.269057511236, 8135.267825895698, 8476.533790641573, 8484.32125429964, 8581.227689507974, 8601.182887625666, 6134.693265336805, 6156.384361563808, 6158.846028849314, 6184.072389141665, 6241.21984751906, 9817.509124543602, 9997.950010249902, 10016.69949901475, 10043.849342259726, 10049.0, 10235.539400727133, 10268.383896966207, 10509.211809114195, 10570.630027948984, 10606.522706478381, 7096.8935465967015, 7643.808904777203, 7780.766577002699, 7917.247861453153, 7935.484193527403, 8727.0, 8752.081031491856, 8754.21212090915, 8791.472511649321, 8809.119088091396, 7550.471466997257, 7702.614869256403, 7769.339606133316, 7793.454458187873, 7841.882371764314, 7498.58757768328, 7579.848403032019, 7644.503107298008, 7755.457118001911, 7785.0, 7233.240509746612, 7284.417246620152, 7318.3413492783775, 7323.523970942085, 7330.853382932418, 4069.7965101744203, 4755.167845627071, 4788.186008378653, 4918.483559226373, 5059.519345662156, 5045.167547354646, 5080.695158290514, 5103.336566246364, 5110.310108135354, 5120.590352771695], "value": 4609.091775654811, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.8301585080653, 316.7925009119027, 323.8186615495322, 323.96598357172496, 329.52219282041045, 4733.721895088328, 4793.400824896888, 5010.897602527447, 5346.155576326161, 5605.1592261160795, 4356.085222103362, 4605.723656580599, 4795.760211989404, 5091.872703182413, 5604.355499117602, 3810.771353718772, 4009.4186342980324, 4078.3474644056955, 4085.754854708727, 4250.4899412070445, 3810.428435734634, 4178.247915375231, 4183.790810459464, 4204.348326009474, 4266.360045993095, 4140.585941405865, 4203.180379825935, 4212.831486740527, 4223.429836972, 4225.964638663517, 3221.710046095839, 3783.6973042156837, 4045.474088368508, 4224.809883555247, 4249.811347156217, 2999.805012674169, 3353.1103919453562, 3383.5263063171174, 3645.6172101929446, 3666.816659167031, 4610.746408947506, 4836.322914791932, 5290.153575427951, 5331.1873962852815, 5377.327834020747, 4293.656507479394, 4604.431565536703, 4652.162610730067, 4880.951190488086, 4885.169521181408, 4863.805447782084, 5113.874947511542, 5477.534409575191, 5810.883782324372, 5839.270091238595, 3837.501124853751, 4002.9199416011807, 4047.433359329696, 4066.146109317047, 4075.5313138988804, 5112.539871411554, 5136.743162841843, 5316.680999140064, 5831.212786273841, 5948.256467941507, 3550.591681956608, 4092.447519584818, 4105.542532401015, 4128.318827393507, 4132.318167502389, 3847.5190601174854, 3862.575116737157, 4191.517975432804, 4194.385161712701, 4194.790260486963, 4590.701604395704, 4647.512011238839, 4789.808407663724, 4793.209120495149, 4878.82924097662, 5090.745462726923, 5120.618912205768, 5219.582433405319, 5858.941410585925, 5891.1457838614015, 1279.2516377918835, 1292.9676758081123, 1325.8475275343267, 1339.9682244671744, 1382.8962827787957, 852.552409984763, 876.640577363278, 896.9910300897038, 909.5452273863068, 951.6431338248157, 4985.152524070844, 4989.902221511262, 5344.839654810362, 5436.891262174734, 5437.619366644376, 5045.344105266292, 5046.697198168048, 5348.518633322942, 5952.672603006876, 6020.608660437145, 4154.815877474905, 4754.595859351924, 4892.461829198785, 5168.767405466763, 5182.32629758129, 2938.617979662673, 2989.5814585957983, 3061.892833750808, 3152.574402455685, 3178.380215857932, 5065.594752419797, 5526.590719366536, 5717.828465145969, 5936.7328470218945, 6171.9691401542705, 5848.637033705636, 5938.61399009063, 6175.752969881154, 6177.227846519185, 6200.689965501707, 5483.835484935458, 5559.971405290034, 5613.8596535086135, 6212.875742485125, 6245.5628106032145, 5334.706591137525, 5781.147914233147, 5823.79616713413, 5918.526517878646, 6014.0, 5050.646454748133, 5886.58793884424, 5914.674692891932, 5944.881102377842, 5983.307511619875, 5537.916931246115, 5654.830355089354, 5810.605454690759, 6198.783042593487, 6261.843453913689, 3587.6232995535092, 3827.392495151987, 3938.5470670873215, 4070.4911886014247, 4141.91716165681, 5245.898361344008, 5658.830235092953, 5969.432903874125, 5979.551533635082, 5983.696276933325, 4972.707096155098, 5363.256941493952, 5594.1608758686525, 5898.380670029756, 5922.822315330547, 5159.561437277874, 5788.160716696139, 5809.11203858756, 5828.504577110993, 5829.2893804381165, 4792.0, 5037.672551284155, 5084.6440749147205, 5451.827857010761, 5517.200005999028, 4658.37111989877, 4919.655624106278, 5891.49922256613, 5912.0, 5943.673097979653, 5797.913031304618, 5815.912761308668, 5840.1531777892815, 5849.064149736022, 5904.638144742137], "value": 4166.305166294183, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.7094307561597, 675.1526833823557, 683.7743544630273, 728.6519938114488, 742.5136535569204, 15025.549233522985, 15412.30644620992, 15577.532674019769, 15693.764593531077, 15715.764263536043, 14356.712865742706, 14483.986120971536, 14499.92750036251, 14552.781708274384, 14554.563363099098, 692.1866806502364, 873.5370253765515, 894.1684233662675, 921.1893533690343, 921.2906062332, 885.4465958775766, 897.2642433204775, 907.718607231757, 912.0651332384326, 917.4953775423523, 966.7631430299587, 982.5087456271864, 985.3939827006375, 987.1313244344966, 987.9851802222972, 938.9061093890556, 947.5830634520789, 970.4759429907876, 970.898055704148, 973.7711637765119, 723.9782806515813, 725.408791834653, 728.0171768113058, 729.8321386081232, 732.8607564562716, 14500.927495362455, 14671.55985320442, 14781.0, 14871.70256594862, 14907.403703851833, 12596.37018149098, 12665.556705515262, 12788.040896932675, 12799.976001919824, 13011.934940325333, 9695.70912872615, 10087.949560252226, 10130.240231982558, 10244.846327305097, 10368.585256589728, 3077.753509828523, 3247.171971147366, 3476.7566270361085, 3505.4216054351, 3564.1624218308675, 70856.58286834235, 86591.0, 88290.55854720749, 88669.22661546797, 89397.65903511453, 9505.239580833517, 9527.237820974306, 9609.0, 9813.705588832347, 9879.506024698736, 19299.228030878607, 22567.88716056442, 22615.773842261697, 22633.4341641457, 22697.0, 6435.839104022437, 7338.853222935618, 7353.8161545961775, 7544.547327160388, 7695.807604809813, 3896.8636097736444, 3965.6232657897453, 3965.722399432012, 3969.0275882408996, 3989.840406383712, 3581.7313701472494, 3613.7109031277437, 3618.674319311222, 3671.2290419011774, 3688.243909998444, 619.7180282971327, 630.130420020369, 633.4267487923437, 633.4963703855447, 639.3127388057763, 472.2538389344827, 539.6815878631547, 551.2723205368875, 571.6484362117268, 599.3946114424463, 7606.682729644123, 7792.376609871197, 7797.454178207585, 7960.681572737026, 8021.919780802234, 498.3969397029635, 513.7585334892594, 520.456123351099, 523.9423663397023, 545.9454054594509, 3621.6559426854406, 3664.4136938089778, 3752.793596352227, 3807.238552289498, 3850.5956874528333, 99697.50151249343, 102819.97180028104, 103129.0, 104467.0, 105529.0, 67427.66286168636, 83116.16883831084, 83118.0, 85162.57418712867, 86787.56606217055, 73771.63114184394, 74813.50372992619, 77794.0, 79499.60250198828, 79962.20037799663, 7209.243029481829, 7226.349628533457, 7299.598522081336, 7443.330100291, 7615.238476152562, 45432.545674544155, 45522.77238613918, 46622.53377466318, 47231.763841180575, 48728.75635621799, 65350.366240844356, 66140.66929665321, 68775.96836047363, 68953.0, 70030.64984675044, 10236.232282578687, 10260.486975651336, 10323.63867264642, 10454.42500662471, 10618.05548622825, 11177.66467005991, 11196.384198868815, 11329.263597866113, 11546.076313895037, 11707.707307317385, 9256.935452423058, 10393.844092338772, 10648.148148147975, 10756.193285503776, 10792.406417647106, 9527.761805954906, 10144.0, 10367.0, 10384.532696028848, 10387.065164134961, 10361.274710770174, 10380.27338086357, 10406.895931040593, 10694.251402401756, 10705.785884282426, 8869.822603548022, 8931.508767017876, 9260.907390926004, 9319.720408387759, 9501.524923753646, 8779.956100219459, 8780.429272097294, 9388.497840345512, 9448.149666530046, 9486.292467355666], "value": 6790.693745647286, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1620.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 110.0, 123.0, 119.0, 95.0, 99.0, 119.0, 95.0, 109.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 104.0, 130.0, 210.0, 121.0, 132.0, 134.0, 146.0, 182.0, 168.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 208.0, 266.0, 243.0, 194.0, 198.0, 195.0, 201.0, 198.0, 197.0], "value": 210.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 75.0, 174.0, 57.0, 57.0, 63.0, 69.0, 136.0, 69.0], "value": 84.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 173.0, 66.0, 105.0, 121.0, 160.0, 100.0, 80.0, 122.0], "value": 106.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 88.0, 89.0, 86.0, 90.0, 88.0, 137.0, 87.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 123.0, 98.0, 94.0, 95.0, 95.0, 120.0, 138.0, 95.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 143.0, 92.0, 95.0, 125.0, 148.0, 90.0, 91.0, 92.0, 90.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 59.0, 58.0, 58.0, 58.0, 51.0, 130.0, 95.0, 88.0], "value": 71.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 66.0, 71.0, 71.0, 71.0, 71.0, 65.0, 66.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 94.0, 75.0, 81.0, 105.0, 107.0, 108.0, 98.0, 88.0, 87.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 158.0, 147.0, 137.0, 135.0, 140.0, 149.0, 156.0, 235.0, 174.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 163.0, 170.0, 221.0, 224.0, 173.0, 220.0, 167.0, 223.0, 203.0], "value": 196.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 68.0, 64.0, 78.0, 86.0, 61.0, 56.0, 82.0], "value": 68.7, "unit": "score"}]}]} 04:22:32 INFO - Return code: 0 04:22:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:22:32 ERROR - # TBPL SUCCESS # 04:22:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:22:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:22:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:22:32 INFO - 2016-05-19 04:22:32.948 screenresolution[1680:10753] starting screenresolution argv=screenresolution get 04:22:32 INFO - 2016-05-19 04:22:32.951 screenresolution[1680:10753] Display 0: 1600x1200x32@60 04:22:32 INFO - 2016-05-19 04:22:32.957 screenresolution[1681:10756] starting screenresolution argv=screenresolution list 04:22:32 INFO - Available Modes on Display 0 04:22:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:22:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:22:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:22:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:22:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:22:33 INFO - Intel Iris: 04:22:33 INFO - Chipset Model: Intel Iris 04:22:33 INFO - Type: GPU 04:22:33 INFO - Bus: Built-In 04:22:33 INFO - VRAM (Dynamic, Max): 1536 MB 04:22:33 INFO - Vendor: Intel (0x8086) 04:22:33 INFO - Device ID: 0x0a2e 04:22:33 INFO - Revision ID: 0x0009 04:22:33 INFO - Displays: 04:22:33 INFO - Display: 04:22:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:22:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:22:33 INFO - Display Serial Number: 1600x1200 60 04:22:33 INFO - Main Display: Yes 04:22:33 INFO - Mirror: Off 04:22:33 INFO - Online: Yes 04:22:33 INFO - Rotation: Supported 04:22:33 INFO - Return code: 0 04:22:33 INFO - Running post-action listener: _resource_record_post_action 04:22:33 INFO - ##### 04:22:33 INFO - ##### Finished run-tests step (success) 04:22:33 INFO - ##### 04:22:33 INFO - Running post-run listener: _resource_record_post_run 04:22:33 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37881344; Write bytes: 698043904; Read time: 395; Write time: 2015 04:22:33 INFO - TinderboxPrint: CPU usage
54.4% 04:22:33 INFO - TinderboxPrint: I/O read bytes / time
37,881,344 / 395 04:22:33 INFO - TinderboxPrint: I/O write bytes / time
698,043,904 / 2,015 04:22:33 INFO - TinderboxPrint: CPU idle
2,207.3 (45.6%) 04:22:33 INFO - TinderboxPrint: CPU system
973.4 (20.1%) 04:22:33 INFO - TinderboxPrint: CPU user
1,661.0 (34.3%) 04:22:33 INFO - TinderboxPrint: Swap in / out
433,631,232 / 0 04:22:33 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376924672; Write bytes: 371120128; Read time: 28274; Write time: 764 04:22:33 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36922880; Write bytes: 299849216; Read time: 380; Write time: 1194 04:22:33 INFO - Running post-run listener: _upload_blobber_files 04:22:33 INFO - Blob upload gear active. 04:22:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:22:33 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 04:22:33 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'] 04:22:33 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 04:22:34 INFO - (blobuploader) - INFO - Open directory for files ... 04:22:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:22:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:22:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:35 INFO - (blobuploader) - INFO - Done attempting. 04:22:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:22:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:36 INFO - (blobuploader) - INFO - Done attempting. 04:22:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:22:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:22:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:22:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:22:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:22:38 INFO - (blobuploader) - INFO - Done attempting. 04:22:38 INFO - (blobuploader) - INFO - Iteration through files over. 04:22:38 INFO - Return code: 0 04:22:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:22:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:22:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/27c6f4c925e5ad143bd4bacf6fc1de0d78c0c8c23758acd463d015dad12d7cc3556b7944d2ada75f6def7766f58f077cf008cdb47f75e20d13ceb51247b98bbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e48b590444e42c5436eca4b868ef6f63d7686bdf3350426d4d22697ca481afe9a8b57b30b29df271b3df65d5a32c634d4929c492febd29b86bdf7f7a030a2758", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6328b16debe9a6be8904162f0bd1a2bbf995c12631723ccd91d56f2e2522eb07e754ed5aa8dbcf44ccac522c43ce3cadf0be380332062f5de684e3148e0ea23"} 04:22:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:22:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:22:38 INFO - Contents: 04:22:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/27c6f4c925e5ad143bd4bacf6fc1de0d78c0c8c23758acd463d015dad12d7cc3556b7944d2ada75f6def7766f58f077cf008cdb47f75e20d13ceb51247b98bbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e48b590444e42c5436eca4b868ef6f63d7686bdf3350426d4d22697ca481afe9a8b57b30b29df271b3df65d5a32c634d4929c492febd29b86bdf7f7a030a2758", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6328b16debe9a6be8904162f0bd1a2bbf995c12631723ccd91d56f2e2522eb07e754ed5aa8dbcf44ccac522c43ce3cadf0be380332062f5de684e3148e0ea23"} 04:22:38 INFO - Running post-run listener: copy_logs_to_upload_dir 04:22:38 INFO - Copying logs to upload dir... 04:22:38 INFO - mkdir: /builds/slave/test/build/upload/logs 04:22:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.078504 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-19 04:22:38.479498) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 04:22:38.483145) ========= 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.lH8Fn35xAS/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.P3mSBRozDG/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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/27c6f4c925e5ad143bd4bacf6fc1de0d78c0c8c23758acd463d015dad12d7cc3556b7944d2ada75f6def7766f58f077cf008cdb47f75e20d13ceb51247b98bbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e48b590444e42c5436eca4b868ef6f63d7686bdf3350426d4d22697ca481afe9a8b57b30b29df271b3df65d5a32c634d4929c492febd29b86bdf7f7a030a2758", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6328b16debe9a6be8904162f0bd1a2bbf995c12631723ccd91d56f2e2522eb07e754ed5aa8dbcf44ccac522c43ce3cadf0be380332062f5de684e3148e0ea23"} build_url:https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/Z3eCcXvfRcmuMw3PMrNxOQ/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/27c6f4c925e5ad143bd4bacf6fc1de0d78c0c8c23758acd463d015dad12d7cc3556b7944d2ada75f6def7766f58f077cf008cdb47f75e20d13ceb51247b98bbd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e48b590444e42c5436eca4b868ef6f63d7686bdf3350426d4d22697ca481afe9a8b57b30b29df271b3df65d5a32c634d4929c492febd29b86bdf7f7a030a2758", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6328b16debe9a6be8904162f0bd1a2bbf995c12631723ccd91d56f2e2522eb07e754ed5aa8dbcf44ccac522c43ce3cadf0be380332062f5de684e3148e0ea23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 04:22:38.517416) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:22:38.517741) ========= 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.lH8Fn35xAS/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.P3mSBRozDG/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.004415 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:22:38.567182) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 04:22:38.567508) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 04:22:38.567849) ========= ========= Total master_lag: 0.21 =========