builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0363 starttime: 1464650774.44 results: success (0) buildid: 20160530150438 builduid: d48d5abf2de54ddab4372c3106e111c2 revision: 3a87296fe4145138c2ce15512bb31f76fe869cb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.444099) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.444559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.444857) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.701062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.701485) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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-30 16:26:14-- 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% 8.40M=0.001s 2016-05-30 16:26:14 (8.40 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149935 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.882452) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.882783) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.020185 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:14.934979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:26:14.935350) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3a87296fe4145138c2ce15512bb31f76fe869cb4 --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 3a87296fe4145138c2ce15512bb31f76fe869cb4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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-30 16:26:15,011 truncating revision to first 12 chars 2016-05-30 16:26:15,011 Setting DEBUG logging. 2016-05-30 16:26:15,011 attempt 1/10 2016-05-30 16:26:15,011 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3a87296fe414?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 16:26:16,177 unpacking tar archive at: fx-team-3a87296fe414/testing/mozharness/ program finished with exit code 0 elapsedTime=1.443103 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:26:16.399335) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:16.399699) ========= script_repo_revision: 3a87296fe4145138c2ce15512bb31f76fe869cb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:16.400142) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:16.400457) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:26:16.417211) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-30 16:26:16.417622) ========= /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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:26:16 INFO - MultiFileLogger online at 20160530 16:26:16 in /builds/slave/test 16:26:16 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 16:26:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:26:16 INFO - {'append_to_log': False, 16:26:16 INFO - 'base_work_dir': '/builds/slave/test', 16:26:16 INFO - 'blob_upload_branch': 'Fx-Team', 16:26:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:26:16 INFO - 'branch': 'Fx-Team', 16:26:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:26:16 INFO - 'config_files': ('talos/mac_config.py',), 16:26:16 INFO - 'default_actions': ('clobber', 16:26:16 INFO - 'read-buildbot-config', 16:26:16 INFO - 'download-and-extract', 16:26:16 INFO - 'populate-webroot', 16:26:16 INFO - 'create-virtualenv', 16:26:16 INFO - 'install', 16:26:16 INFO - 'run-tests'), 16:26:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:26:16 INFO - 'download_minidump_stackwalk': True, 16:26:16 INFO - 'download_symbols': 'ondemand', 16:26:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:26:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:26:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:26:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:26:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:26:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:26:16 INFO - 'installer_path': 'installer.exe', 16:26:16 INFO - 'log_level': 'info', 16:26:16 INFO - 'log_name': 'talos', 16:26:16 INFO - 'log_to_console': True, 16:26:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:26:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:26:16 INFO - 'opt_config_files': (), 16:26:16 INFO - 'pip_index': False, 16:26:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:16 INFO - 'cmd': ('bash', 16:26:16 INFO - '-c', 16:26:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:16 INFO - 'enabled': True, 16:26:16 INFO - 'halt_on_failure': False, 16:26:16 INFO - 'name': 'check_screen_resolution'},), 16:26:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:26:16 INFO - 'cmd': ('bash', 16:26:16 INFO - '-c', 16:26:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:26:16 INFO - 'enabled': True, 16:26:16 INFO - 'halt_on_failure': False, 16:26:16 INFO - 'name': 'check_screen_resolution'},), 16:26:16 INFO - 'run_cmd_checks_enabled': True, 16:26:16 INFO - 'sps_profile': False, 16:26:16 INFO - 'sps_profile_interval': 0, 16:26:16 INFO - 'suite': 'dromaeojs-e10s', 16:26:16 INFO - 'system_bits': '32', 16:26:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:26:16 INFO - 'title': 't-yosemite-r7-0363', 16:26:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:26:16 INFO - 'use_talos_json': True, 16:26:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:26:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:26:16 INFO - 'work_dir': 'build'} 16:26:16 INFO - ##### 16:26:16 INFO - ##### Running clobber step. 16:26:16 INFO - ##### 16:26:16 INFO - Running pre-action listener: _resource_record_pre_action 16:26:16 INFO - Running main action method: clobber 16:26:16 INFO - rmtree: /builds/slave/test/build 16:26:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:26:17 INFO - Running post-action listener: _resource_record_post_action 16:26:17 INFO - ##### 16:26:17 INFO - ##### Finished clobber step (success) 16:26:17 INFO - ##### 16:26:17 INFO - ##### 16:26:17 INFO - ##### Running read-buildbot-config step. 16:26:17 INFO - ##### 16:26:17 INFO - Running pre-action listener: _resource_record_pre_action 16:26:17 INFO - Running main action method: read_buildbot_config 16:26:17 INFO - Using buildbot properties: 16:26:17 INFO - { 16:26:17 INFO - "project": "", 16:26:17 INFO - "product": "firefox", 16:26:17 INFO - "script_repo_revision": "production", 16:26:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 16:26:17 INFO - "repository": "", 16:26:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 16:26:17 INFO - "buildid": "20160530150438", 16:26:17 INFO - "pgo_build": "False", 16:26:17 INFO - "basedir": "/builds/slave/test", 16:26:17 INFO - "buildnumber": 690, 16:26:17 INFO - "slavename": "t-yosemite-r7-0363", 16:26:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:26:17 INFO - "platform": "yosemite_r7", 16:26:17 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 16:26:17 INFO - "branch": "fx-team", 16:26:17 INFO - "repo_path": "integration/fx-team", 16:26:17 INFO - "revision": "3a87296fe4145138c2ce15512bb31f76fe869cb4", 16:26:17 INFO - "stage_platform": "macosx64", 16:26:17 INFO - "builduid": "d48d5abf2de54ddab4372c3106e111c2", 16:26:17 INFO - "slavebuilddir": "test" 16:26:17 INFO - } 16:26:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:26:17 INFO - Running post-action listener: _resource_record_post_action 16:26:17 INFO - ##### 16:26:17 INFO - ##### Finished read-buildbot-config step (success) 16:26:17 INFO - ##### 16:26:17 INFO - ##### 16:26:17 INFO - ##### Running download-and-extract step. 16:26:17 INFO - ##### 16:26:17 INFO - Running pre-action listener: _resource_record_pre_action 16:26:17 INFO - Running main action method: download_and_extract 16:26:17 INFO - mkdir: /builds/slave/test/build/tests 16:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:17 INFO - https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:26:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:26:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:26:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:26:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:26:47 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 16:26:47 INFO - retry: attempt #1 caught exception: timed out 16:26:47 INFO - retry: Failed, sleeping 30 seconds before retrying 16:27:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 #2 16:27:20 INFO - Downloaded 1199 bytes. 16:27:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:27:20 INFO - Using the following test package requirements: 16:27:20 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:27:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:27:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:27:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'jsshell-mac.zip'], 16:27:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:27:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:27:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:27:20 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:27:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:27:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:27:20 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:27:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:27:20 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')]} 16:27:20 INFO - https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:27:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:27:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:27:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:25 INFO - Downloaded 18684731 bytes. 16:27:25 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'] 16:27:25 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 16:27:28 INFO - Return code: 0 16:27:28 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 16:27:28 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')]} 16:27:28 INFO - https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:27:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:27:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:27:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:29 INFO - Downloaded 18684731 bytes. 16:27:29 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'] 16:27:29 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 16:27:32 INFO - Return code: 0 16:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:32 INFO - https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:27:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:27:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:27:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:33 INFO - Downloaded 10834055 bytes. 16:27:33 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'] 16:27:33 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 16:27:33 INFO - Return code: 0 16:27: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')]} 16:27:33 INFO - https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:27:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:27:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:27:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:27:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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 16:27:40 INFO - Downloaded 110900352 bytes. 16:27:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:27:40 INFO - mkdir: /builds/slave/test/properties 16:27:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:27:40 INFO - Writing to file /builds/slave/test/properties/build_url 16:27:40 INFO - Contents: 16:27:40 INFO - build_url:https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:27:41 INFO - Running post-action listener: _resource_record_post_action 16:27:41 INFO - Running post-action listener: set_extra_try_arguments 16:27:41 INFO - ##### 16:27:41 INFO - ##### Finished download-and-extract step (success) 16:27:41 INFO - ##### 16:27:41 INFO - ##### 16:27:41 INFO - ##### Running populate-webroot step. 16:27:41 INFO - ##### 16:27:41 INFO - Running pre-action listener: _resource_record_pre_action 16:27:41 INFO - Running main action method: populate_webroot 16:27:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:27:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:27:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:27:41 INFO - '--tppagecycles', 16:27:41 INFO - '7'], 16:27:41 INFO - 'tests': ['tsvgm']}}, 16:27:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:27:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:27:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:27:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:27:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:27:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['damp', 'tps']}, 16:27:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'tests': ['damp', 'tps']}, 16:27:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['dromaeo_dom']}, 16:27:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:27:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other-e10s': {'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['a11yr', 16:27:41 INFO - 'ts_paint', 16:27:41 INFO - 'tpaint', 16:27:41 INFO - 'sessionrestore', 16:27:41 INFO - 'sessionrestore_no_auto_restore', 16:27:41 INFO - 'tabpaint']}, 16:27:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tsvgx', 16:27:41 INFO - 'tsvgr_opacity', 16:27:41 INFO - 'tart', 16:27:41 INFO - 'tscrollx', 16:27:41 INFO - 'cart']}, 16:27:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:27:41 INFO - 'tsvgr_opacity', 16:27:41 INFO - 'tart', 16:27:41 INFO - 'tscrollx', 16:27:41 INFO - 'cart']}, 16:27:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s'], 16:27:41 INFO - 'tests': ['tp5o']}, 16:27:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'tests': ['tp5o']}, 16:27:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--disable-e10s', 16:27:41 INFO - '--xperf_path', 16:27:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:41 INFO - 'tests': ['tp5n']}, 16:27:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:27:41 INFO - 'talos_options': ['--xperf_path', 16:27:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:27:41 INFO - 'tests': ['tp5n']}}, 16:27:41 INFO - 'talos.zip': {'path': '', 16:27:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:27:41 INFO - Running post-action listener: _resource_record_post_action 16:27:41 INFO - ##### 16:27:41 INFO - ##### Finished populate-webroot step (success) 16:27:41 INFO - ##### 16:27:41 INFO - ##### 16:27:41 INFO - ##### Running create-virtualenv step. 16:27:41 INFO - ##### 16:27:41 INFO - Running pre-action listener: _resource_record_pre_action 16:27:41 INFO - Running main action method: create_virtualenv 16:27:41 INFO - Creating virtualenv /builds/slave/test/build/venv 16:27:41 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 16:27:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:27:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:27:41 INFO - Using real prefix '/tools/python27' 16:27:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:27:41 INFO - Installing distribute.............................................................................................................................................................................................done. 16:27:44 INFO - Installing pip.................done. 16:27:44 INFO - Return code: 0 16:27:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:27:44 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 16:27:44 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 16:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:44 INFO - 'HOME': '/Users/cltbld', 16:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:44 INFO - 'LOGNAME': 'cltbld', 16:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:44 INFO - 'MOZ_NO_REMOTE': '1', 16:27:44 INFO - 'NO_EM_RESTART': '1', 16:27:44 INFO - 'PAGER': '/bin/cat', 16:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:44 INFO - 'PWD': '/builds/slave/test', 16:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:44 INFO - 'SHELL': '/bin/bash', 16:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:27:44 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:44 INFO - 'USER': 'cltbld', 16:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:44 INFO - 'XPC_FLAGS': '0x0', 16:27:44 INFO - 'XPC_SERVICE_NAME': '0', 16:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:44 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 16:27:44 INFO - Cleaning up... 16:27:44 INFO - Return code: 0 16:27:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:44 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:27:44 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 16:27:44 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 16:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:44 INFO - 'HOME': '/Users/cltbld', 16:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:44 INFO - 'LOGNAME': 'cltbld', 16:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:44 INFO - 'MOZ_NO_REMOTE': '1', 16:27:44 INFO - 'NO_EM_RESTART': '1', 16:27:44 INFO - 'PAGER': '/bin/cat', 16:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:44 INFO - 'PWD': '/builds/slave/test', 16:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:44 INFO - 'SHELL': '/bin/bash', 16:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:27:44 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:44 INFO - 'USER': 'cltbld', 16:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:44 INFO - 'XPC_FLAGS': '0x0', 16:27:44 INFO - 'XPC_SERVICE_NAME': '0', 16:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:45 INFO - Downloading/unpacking psutil>=3.1.1 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:27:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:48 INFO - Installing collected packages: psutil 16:27:48 INFO - Running setup.py install for psutil 16:27:48 INFO - building 'psutil._psutil_osx' extension 16:27:48 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 16:27:48 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 16:27:48 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 16:27:48 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 16:27:48 INFO - building 'psutil._psutil_posix' extension 16:27:48 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 16:27:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:27:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:27:48 INFO - ^ 16:27:49 INFO - 1 warning generated. 16:27:49 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 16:27:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:27:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:27:49 INFO - Successfully installed psutil 16:27:49 INFO - Cleaning up... 16:27:49 INFO - Return code: 0 16:27:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:27:49 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')]} 16:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:49 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')]} 16:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:49 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:27:49 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 16:27:49 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 16:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:49 INFO - 'HOME': '/Users/cltbld', 16:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:49 INFO - 'LOGNAME': 'cltbld', 16:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:49 INFO - 'MOZ_NO_REMOTE': '1', 16:27:49 INFO - 'NO_EM_RESTART': '1', 16:27:49 INFO - 'PAGER': '/bin/cat', 16:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:49 INFO - 'PWD': '/builds/slave/test', 16:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:49 INFO - 'SHELL': '/bin/bash', 16:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:27:49 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:49 INFO - 'USER': 'cltbld', 16:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:49 INFO - 'XPC_FLAGS': '0x0', 16:27:49 INFO - 'XPC_SERVICE_NAME': '0', 16:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:27:49 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 16:27:49 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 16:27:49 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 16:27:49 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 16:27:49 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 16:27:49 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 16:27:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:27:52 INFO - Installing collected packages: mozsystemmonitor 16:27:52 INFO - Running setup.py install for mozsystemmonitor 16:27:52 INFO - Successfully installed mozsystemmonitor 16:27:52 INFO - Cleaning up... 16:27:52 INFO - Return code: 0 16:27:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:27: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')]} 16:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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', '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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:27: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:27: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 blobuploader==1.2.4 16:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:52 INFO - 'HOME': '/Users/cltbld', 16:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:52 INFO - 'LOGNAME': 'cltbld', 16:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:52 INFO - 'MOZ_NO_REMOTE': '1', 16:27:52 INFO - 'NO_EM_RESTART': '1', 16:27:52 INFO - 'PAGER': '/bin/cat', 16:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:52 INFO - 'PWD': '/builds/slave/test', 16:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:52 INFO - 'SHELL': '/bin/bash', 16:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:27:52 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:52 INFO - 'USER': 'cltbld', 16:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:52 INFO - 'XPC_FLAGS': '0x0', 16:27:52 INFO - 'XPC_SERVICE_NAME': '0', 16:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:52 INFO - Downloading/unpacking blobuploader==1.2.4 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27:54 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:54 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 16:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:27:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:55 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 16:27:55 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 16:27:55 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 16:27:55 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 16:27:55 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 16:27:55 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 16:27:56 INFO - Downloading docopt-0.6.1.tar.gz 16:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:56 INFO - Running setup.py install for blobuploader 16:27:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:27:56 INFO - Running setup.py install for requests 16:27:56 INFO - Running setup.py install for docopt 16:27:56 INFO - Successfully installed blobuploader requests docopt 16:27:56 INFO - Cleaning up... 16:27:57 INFO - Return code: 0 16:27:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:27:57 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')]} 16:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:57 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')]} 16:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:57 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:27:57 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 16:27:57 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 16:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:57 INFO - 'HOME': '/Users/cltbld', 16:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:57 INFO - 'LOGNAME': 'cltbld', 16:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:57 INFO - 'MOZ_NO_REMOTE': '1', 16:27:57 INFO - 'NO_EM_RESTART': '1', 16:27:57 INFO - 'PAGER': '/bin/cat', 16:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:57 INFO - 'PWD': '/builds/slave/test', 16:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:57 INFO - 'SHELL': '/bin/bash', 16:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:27:57 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:57 INFO - 'USER': 'cltbld', 16:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:57 INFO - 'XPC_FLAGS': '0x0', 16:27:57 INFO - 'XPC_SERVICE_NAME': '0', 16:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-kH_zuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-YCnZyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-iSE8Nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-S4pcWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-r9Ml5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-WYSHLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:27:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-DlRMqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ghoaRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-smEMJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-GWkrNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-77LUUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-6zQmT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-TdjDVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-M1YOmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-RBsif8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-FqlvfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:27:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:27:58 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-QYR2z0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:27:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:59 INFO - Running setup.py install for manifestparser 16:27:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:27:59 INFO - Running setup.py install for mozcrash 16:27:59 INFO - Running setup.py install for mozdebug 16:27:59 INFO - Running setup.py install for mozdevice 16:27:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:27:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:27:59 INFO - Running setup.py install for mozfile 16:27:59 INFO - Running setup.py install for mozhttpd 16:27:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:27:59 INFO - Running setup.py install for mozinfo 16:28:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Running setup.py install for mozInstall 16:28:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Running setup.py install for mozleak 16:28:00 INFO - Running setup.py install for mozlog 16:28:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Running setup.py install for moznetwork 16:28:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:00 INFO - Running setup.py install for mozprocess 16:28:00 INFO - Running setup.py install for mozprofile 16:28:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:01 INFO - Running setup.py install for mozrunner 16:28:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:01 INFO - Running setup.py install for mozscreenshot 16:28:01 INFO - Running setup.py install for moztest 16:28:01 INFO - Running setup.py install for mozversion 16:28:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:01 INFO - Cleaning up... 16:28:01 INFO - Return code: 0 16:28:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:01 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:28:01 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 16:28:01 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 16:28:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:28:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:01 INFO - 'HOME': '/Users/cltbld', 16:28:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:01 INFO - 'LOGNAME': 'cltbld', 16:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:01 INFO - 'MOZ_NO_REMOTE': '1', 16:28:01 INFO - 'NO_EM_RESTART': '1', 16:28:01 INFO - 'PAGER': '/bin/cat', 16:28:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:01 INFO - 'PWD': '/builds/slave/test', 16:28:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:01 INFO - 'SHELL': '/bin/bash', 16:28:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:28:01 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:28:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:01 INFO - 'USER': 'cltbld', 16:28:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:01 INFO - 'XPC_FLAGS': '0x0', 16:28:01 INFO - 'XPC_SERVICE_NAME': '0', 16:28:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-2UBbST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-YsStAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-lCspwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-CYncko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-XlA2wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-TjTDPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-gKyQFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-gi5oAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-MTWHS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Gd31D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:02 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)) 16:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-CZKm16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:03 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)) 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-BVQRH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:03 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)) 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-1mrbMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:03 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)) 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-kvLXJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:03 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)) 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-lGP5p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:03 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)) 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-fNIDON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:03 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)) 16:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-pquk7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 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)) 16:28:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28: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 16:28: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 16:28: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 16:28: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 16:28: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 16:28: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 16:28:06 INFO - Downloading blessings-1.6.tar.gz 16:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:06 INFO - Installing collected packages: blessings 16:28:06 INFO - Running setup.py install for blessings 16:28:06 INFO - Successfully installed blessings 16:28:06 INFO - Cleaning up... 16:28:06 INFO - Return code: 0 16:28:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:06 INFO - Reading from file tmpfile_stdout 16:28:06 INFO - Current package versions: 16:28:06 INFO - blessings == 1.6 16:28:06 INFO - blobuploader == 1.2.4 16:28:06 INFO - docopt == 0.6.1 16:28:06 INFO - manifestparser == 1.1 16:28:06 INFO - mozInstall == 1.12 16:28:06 INFO - mozcrash == 0.17 16:28:06 INFO - mozdebug == 0.1 16:28:06 INFO - mozdevice == 0.48 16:28:06 INFO - mozfile == 1.2 16:28:06 INFO - mozhttpd == 0.7 16:28:06 INFO - mozinfo == 0.9 16:28:06 INFO - mozleak == 0.1 16:28:06 INFO - mozlog == 3.2 16:28:06 INFO - moznetwork == 0.27 16:28:06 INFO - mozprocess == 0.22 16:28:06 INFO - mozprofile == 0.28 16:28:06 INFO - mozrunner == 6.11 16:28:06 INFO - mozscreenshot == 0.1 16:28:06 INFO - mozsystemmonitor == 0.3 16:28:06 INFO - moztest == 0.7 16:28:06 INFO - mozversion == 1.4 16:28:06 INFO - psutil == 3.1.1 16:28:06 INFO - requests == 1.2.3 16:28:06 INFO - wsgiref == 0.1.2 16:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:06 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:28:06 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 16:28:06 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 16:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:06 INFO - 'HOME': '/Users/cltbld', 16:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:06 INFO - 'LOGNAME': 'cltbld', 16:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:06 INFO - 'MOZ_NO_REMOTE': '1', 16:28:06 INFO - 'NO_EM_RESTART': '1', 16:28:06 INFO - 'PAGER': '/bin/cat', 16:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:06 INFO - 'PWD': '/builds/slave/test', 16:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:06 INFO - 'SHELL': '/bin/bash', 16:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:28:06 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:06 INFO - 'USER': 'cltbld', 16:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:06 INFO - 'XPC_FLAGS': '0x0', 16:28:06 INFO - 'XPC_SERVICE_NAME': '0', 16:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 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)) 16:28:07 INFO - Cleaning up... 16:28:07 INFO - Return code: 0 16:28:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:28:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:07 INFO - 'HOME': '/Users/cltbld', 16:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:07 INFO - 'LOGNAME': 'cltbld', 16:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:07 INFO - 'MOZ_NO_REMOTE': '1', 16:28:07 INFO - 'NO_EM_RESTART': '1', 16:28:07 INFO - 'PAGER': '/bin/cat', 16:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:07 INFO - 'PWD': '/builds/slave/test', 16:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:07 INFO - 'SHELL': '/bin/bash', 16:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:28:07 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:07 INFO - 'USER': 'cltbld', 16:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:07 INFO - 'XPC_FLAGS': '0x0', 16:28:07 INFO - 'XPC_SERVICE_NAME': '0', 16:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:07 INFO - Downloading/unpacking jsonschema 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:28:09 INFO - Downloading/unpacking functools32 (from jsonschema) 16:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:28:10 INFO - warning: no files found matching '*.txt' 16:28:10 INFO - no previously-included directories found matching 'build' 16:28:10 INFO - no previously-included directories found matching 'dist' 16:28:10 INFO - no previously-included directories found matching '.git*' 16:28:10 INFO - Installing collected packages: jsonschema, functools32 16:28:10 INFO - Running setup.py install for functools32 16:28:10 INFO - warning: no files found matching '*.txt' 16:28:10 INFO - no previously-included directories found matching 'build' 16:28:10 INFO - no previously-included directories found matching 'dist' 16:28:10 INFO - no previously-included directories found matching '.git*' 16:28:10 INFO - Successfully installed jsonschema functools32 16:28:10 INFO - Cleaning up... 16:28:10 INFO - Return code: 0 16:28:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:28:10 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')]} 16:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:10 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')]} 16:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:10 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 0x1087f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3d0595200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, '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.Xu3SA2YZqE/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.caA2MhArkd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 16:28:10 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 16:28:10 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 16:28:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:28:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:10 INFO - 'HOME': '/Users/cltbld', 16:28:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:10 INFO - 'LOGNAME': 'cltbld', 16:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:10 INFO - 'MOZ_NO_REMOTE': '1', 16:28:10 INFO - 'NO_EM_RESTART': '1', 16:28:10 INFO - 'PAGER': '/bin/cat', 16:28:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:10 INFO - 'PWD': '/builds/slave/test', 16:28:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:10 INFO - 'SHELL': '/bin/bash', 16:28:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:28:10 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:28:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:10 INFO - 'USER': 'cltbld', 16:28:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:10 INFO - 'XPC_FLAGS': '0x0', 16:28:10 INFO - 'XPC_SERVICE_NAME': '0', 16:28:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:10 INFO - Downloading/unpacking flake8 16:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:28:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:28:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:28: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 16:28: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 16:28: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 16:28: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 16:28: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 16:28: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 16:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:28:14 INFO - no previously-included directories found matching 'docs/_build' 16:28:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:28:14 INFO - Running setup.py install for pep8 16:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:28:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:28:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:28:14 INFO - no previously-included directories found matching 'docs/_build' 16:28:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:28:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:28:14 INFO - Cleaning up... 16:28:14 INFO - Return code: 0 16:28:14 INFO - Running post-action listener: _resource_record_post_action 16:28:14 INFO - Running post-action listener: _start_resource_monitoring 16:28:14 INFO - Starting resource monitoring. 16:28:14 INFO - ##### 16:28:14 INFO - ##### Finished create-virtualenv step (success) 16:28:14 INFO - ##### 16:28:14 INFO - ##### 16:28:14 INFO - ##### Running install step. 16:28:14 INFO - ##### 16:28:14 INFO - Running pre-action listener: _resource_record_pre_action 16:28:14 INFO - Running main action method: install 16:28:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:15 INFO - Reading from file tmpfile_stdout 16:28:15 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:15 INFO - Reading from file tmpfile_stdout 16:28:15 INFO - Output received: 16:28:15 INFO - Usage: mozinstall [options] installer 16:28:15 INFO - Options: 16:28:15 INFO - -h, --help show this help message and exit 16:28:15 INFO - -d DEST, --destination=DEST 16:28:15 INFO - Directory to install application into. [default: 16:28:15 INFO - "/builds/slave/test"] 16:28:15 INFO - --app=APP Application being installed. [default: firefox] 16:28:15 INFO - mkdir: /builds/slave/test/build/application 16:28:15 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'] 16:28:15 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 16:28:48 INFO - Reading from file tmpfile_stdout 16:28:48 INFO - Output received: 16:28:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:28:48 INFO - Running post-action listener: _resource_record_post_action 16:28:48 INFO - ##### 16:28:48 INFO - ##### Finished install step (success) 16:28:48 INFO - ##### 16:28:48 INFO - ##### 16:28:48 INFO - ##### Running run-tests step. 16:28:48 INFO - ##### 16:28:48 INFO - Running pre-action listener: _resource_record_pre_action 16:28:48 INFO - Running main action method: run_tests 16:28:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:28:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:28:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:28:48 INFO - 2016-05-30 16:28:48.967 screenresolution[1964:11419] starting screenresolution argv=screenresolution get 16:28:48 INFO - 2016-05-30 16:28:48.970 screenresolution[1964:11419] Display 0: 1600x1200x32@60 16:28:48 INFO - 2016-05-30 16:28:48.976 screenresolution[1965:11421] starting screenresolution argv=screenresolution list 16:28:48 INFO - Available Modes on Display 0 16:28:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:28:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:28:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:28:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:28:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:28:49 INFO - Intel Iris: 16:28:49 INFO - Chipset Model: Intel Iris 16:28:49 INFO - Type: GPU 16:28:49 INFO - Bus: Built-In 16:28:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:28:49 INFO - Vendor: Intel (0x8086) 16:28:49 INFO - Device ID: 0x0a2e 16:28:49 INFO - Revision ID: 0x0009 16:28:49 INFO - Displays: 16:28:49 INFO - Display: 16:28:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:28:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:28:49 INFO - Display Serial Number: 1600x1200 60 16:28:49 INFO - Main Display: Yes 16:28:49 INFO - Mirror: Off 16:28:49 INFO - Online: Yes 16:28:49 INFO - Rotation: Supported 16:28:49 INFO - Return code: 0 16:28:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:49 INFO - Python 2.7.3 16:28:49 INFO - Return code: 0 16:28:49 INFO - grabbing minidump binary from tooltool 16:28:49 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')]} 16:28:49 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 0x108698d30>, '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 0x1084bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10868fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:49 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 16:28:49 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 16:28:49 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 16:28:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:49 INFO - Return code: 0 16:28:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:28:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:28:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:28:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:28:50 INFO - Return code: 0 16:28:50 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/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 16:28:50 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/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0363 --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 16:28:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xu3SA2YZqE/Render', 16:28:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:28:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:28:50 INFO - 'HOME': '/Users/cltbld', 16:28:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:28:50 INFO - 'LOGNAME': 'cltbld', 16:28:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:28:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:50 INFO - 'MOZ_NO_REMOTE': '1', 16:28:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:50 INFO - 'NO_EM_RESTART': '1', 16:28:50 INFO - 'PAGER': '/bin/cat', 16:28:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:28:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:50 INFO - 'PWD': '/builds/slave/test', 16:28:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:28:50 INFO - 'SHELL': '/bin/bash', 16:28:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.caA2MhArkd/Listeners', 16:28:50 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 16:28:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:28:50 INFO - 'USER': 'cltbld', 16:28:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:28:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:28:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:50 INFO - 'XPC_FLAGS': '0x0', 16:28:50 INFO - 'XPC_SERVICE_NAME': '0', 16:28:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:28:50 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/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 16:28:50 INFO - mozversion application_buildid: 20160530150438 16:28:50 INFO - mozversion application_changeset: 3a87296fe4145138c2ce15512bb31f76fe869cb4 16:28:50 INFO - mozversion application_display_name: Nightly 16:28:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:50 INFO - mozversion application_name: Firefox 16:28:50 INFO - mozversion application_remotingname: firefox 16:28:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:28:50 INFO - mozversion application_vendor: Mozilla 16:28:50 INFO - mozversion application_version: 49.0a1 16:28:50 INFO - mozversion platform_buildid: 20160530150438 16:28:50 INFO - mozversion platform_changeset: 3a87296fe4145138c2ce15512bb31f76fe869cb4 16:28:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:28:50 INFO - mozversion platform_version: 49.0a1 16:28:50 INFO - using testdate: 1464650930 16:28:50 INFO - actual date: 1464650930 16:28:50 INFO - starting webserver on 'localhost:49669' 16:28:50 INFO - SUITE-START | Running 2 tests 16:28:50 INFO - TEST-START | dromaeo_css 16:28:50 INFO - operating with platform_type : mac_ 16:28:50 INFO - Initialising browser for dromaeo_css test... 16:28:50 INFO - TEST-INFO | started process 1983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpCTrLJa/profile http://localhost:49669/getInfo.html) 16:28:53 INFO - PROCESS | 1983 | __metrics Screen width/height:1600/1200 16:28:53 INFO - PROCESS | 1983 | colorDepth:24 16:28:53 INFO - PROCESS | 1983 | Browser inner width/height: 1024/689 16:28:53 INFO - PROCESS | 1983 | __metrics 16:28:53 ERROR - PROCESS | 1983 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 16:28:54 INFO - TEST-INFO | 1983: exit 0 16:28:54 INFO - Browser initialized. 16:28:54 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:54 INFO - TEST-INFO | started process 1986 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpCTrLJa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:06 INFO - PROCESS | 1986 | RSS: Main: 252821504 16:29:06 INFO - PROCESS | 1986 | 16:29:56 INFO - PROCESS | 1986 | 1464650996036 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] 16:32:07 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49669/tests/dromaeo/cssquery-ext.html) 16:32:08 INFO - PROCESS | 1986 | RSS: Main: 265310208 16:32:08 INFO - PROCESS | 1986 | 16:35:11 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-ext.html (next: http://localhost:49669/tests/dromaeo/cssquery-jquery.html) 16:35:11 INFO - PROCESS | 1986 | RSS: Main: 276832256 16:35:11 INFO - PROCESS | 1986 | 16:38:13 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49669/tests/dromaeo/cssquery-mootools.html) 16:38:14 INFO - PROCESS | 1986 | RSS: Main: 278761472 16:38:14 INFO - PROCESS | 1986 | 16:41:16 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49669/tests/dromaeo/cssquery-prototype.html) 16:41:16 INFO - PROCESS | 1986 | RSS: Main: 280076288 16:41:16 INFO - PROCESS | 1986 | 16:44:19 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49669/tests/dromaeo/cssquery-yui.html) 16:44:20 INFO - PROCESS | 1986 | RSS: Main: 283873280 16:44:20 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-yui.html (next: http://localhost:49669/tests/dromaeo/cssquery-dojo.html) 16:47:22 INFO - PROCESS | 1986 | RSS: Main: 284037120 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | __start_tp_report 16:47:22 INFO - PROCESS | 1986 | _x_x_mozilla_page_load 16:47:22 INFO - PROCESS | 1986 | _x_x_mozilla_page_load_details 16:47:22 INFO - PROCESS | 1986 | |i|pagename|runs| 16:47:22 INFO - PROCESS | 1986 | |0;dojo.html;3252.7072563469287;3465.8613655453782;3495.1961048958747;3513.8594456221754;3520.894373168804;5829.70851457427;5850.2394688690465;5935.881282374361;5939.435753603411;5978.790742324019;5729.39841316662;5872.530197584194;5887.470127688507;5917.73370198341;6015.879682406349;5187.1181899077155;5252.842414727565;5263.552598029153;5314.627976041678;5359.356877174744;5291.126964050927;5414.052540805363;5489.478499542557;5493.478119578634;5499.560035197174;4235.618794308496;5227.8170264040955;5314.97342513285;5320.866978325554;5357.008953343625;5279.102552566034;5314.415414304423;5323.307969963918;5327.094393953037;5348.91976620351;5235.110031294651;5272.947270527284;5291.1534154535475;5314.654547454403;5366.758495867656;4149.958500415018;5647.124695672177;5722;5748.338941021794;5753.453421924953;6234.064890266451;6249.000159974382;6259.217597800275;6270.059491076329;6291.937080629181;5304.814331498417;6284.308726040132;6310.716017779165;6311.305756366796;6320.841978950563;4944.431390390115;5824.417558244184;5844.211031510734;5979.342272350038;6006.6696331701305;5576.219329293821;5769.652286539819;6134.00801433197;6422.9780620480005;6493.935060649427;5512.889742205213;6075.27096748393;6139.508839292846;6298.685065746694;6298.968505157536;5555.888822235488;6131.908021379683;6239.78160764371;6277.717502712298;6323.019931910561;6124.846878828065;6189.81430557084;6224.751009959551;6249.6875156242;6272.466840318532;6263.68681565929;6284.842878928063;6293;6307.337729538424;6309.242890853127;4977.601791856643;4986.601071914238;5001.974990125026;5024.2463630454995;5051.797928082836;4447.888802779957;4450.221211288012;4451.577100175478;4490.438695163105;4523.095380923828;5779.884402312015;5941.405859414111;5963.075723262901;5966.522678185735;5978.103284507359;6186.752529898844;6212.968935155295;6260.655663938436;6282.277538083087;6319.494440444753;5839.153322768259;5843.561732870052;5844.795432159854;5845.064789633639;5879.823605291848;5401;5446.346438427414;5456.508914197741;5463.808766693147;5474.233607294982;6393.136926514954;6403.903941440882;6440.227172739301;6473.417392434703;6599.406053455212;6266.27937787142;6301.4643755279485;6311.936880631135;6313.55805093639;6316.273628532777;6269.153664255267;6509.576877502947;6548.672566371567;6559.8032059038305;6580.670966451754;6166.260048794174;6181.505479561534;6261.373862613702;6276.1213430118905;6306.0225665022;6271.874562508633;6287.119803227461;6293.527985401021;6293.96853015732;6300.527460440394;5639.943600563942;6139.6454669416935;6187.876242475035;6200.937990620036;6209.875802484015;6153.66154861469;6231.688415579293;6231.9688401559515;6246.937530624636;6257.53068519872;6072.90890636632;6111.113888486234;6130.080487926846;6157.568970172045;6164.137020816999;6088.817335479943;6116.579253789288;6137.009281402767;6190.380961903774;6222.564420490522;6164.815055548341;6166.167567378526;6171.9074213887725;6181.103739957591;6186.5360097993735 16:47:22 INFO - PROCESS | 1986 | |1;ext.html;501.0280056690021;504.13289142674597;518.1657531374486;539.719345940111;568.2186992884783;39326.21347573047;42176.15647687044;42303.36544951824;42322.788386058026;42356.72929812107;42821;42841.571584284226;43696.56303436965;43740.99999999992;43755.34366984498;8280.171982801701;8303.709370172046;9583.329166958316;9660.613575456973;9670.9516452418;9183.49490778007;9594.712158635251;9658;9704;9730.053989202153;9708.320417570772;9746.122848943594;9750.951245243763;9777.217822574177;9814.411135331868;9658.613655453773;9719.61121555137;9734.610615575368;9761.804763904753;9783.706488805346;9582.28132890036;9586.328956973015;9665.371750836173;9702.029797020312;9727.173190278834;32943;40490;40599.18801623981;40608.98477538056;40638.18723625511;3188.4260833050043;3408.659134086596;3449.120474279068;3494.3239533627584;3509.2279698466295;31569;32341;32371.439141694394;33023.50464743031;33442.3311533768;3199;3343.565336506263;3413.4879768034743;3417.8632854685784;3439.7764145330725;520622;526701;532488;532708;533306.333468334;58784.82430351369;69231.96152057723;70952;71049.93425098628;73465.53068938591;74086.62956685181;74137;74185.25814741892;74516.62741686366;74742.62628686822;15196.468123615621;16246.268917898722;17172.914135429244;17200.741988870177;17433.56416089608;12933.417996190194;12968.870311296954;13018.349082545836;13096.541621043218;13249.271290078945;21440.463988400104;21699.89150054271;21726.565468690536;21740.56518869614;21825.345239642837;7639;8455.731640254011;8707.956460217785;8795.47227166372;8893.688720894737;974.4445665970329;1025.4411345816548;1064.1380481809758;1132.6885106595753;1141.2068612314363;26866.731332686813;31129.68870311268;31149.688503114678;31565.526517102262;31909.52135717966;1106.446776611694;1132.6375559820942;1155.526234243956;1188.732535179595;1263.6272299671618;15872.603184920239;16051.277692503865;16141.03153810775;16322.477171273762;16456.83543164553;626212;631693;668758;674235;681867;60162.79674406487;60824;61763.6911815447;61968;62161.378386216464;1208.9214201076902;1241.4785789968234;1452.4843680493273;1455.7670772676533;1457.176695167227;30225.848870756385;30321.545176821925;30424.543631845096;30823.845880770452;30921;51150.23274650809;51296.97406051784;51764.48235517597;51911.74044129755;51982.480175197765;451115;461666;463685;463710;466508;43912;44087.55912440835;44142.77928610337;44678.883027923264;44707;49221.261681074095;49675.75810604764;49880.750596246784;50067.02612529382;50891.2366314513;40051.79974100111;40700.1859962805;41228;41326.38010429906;41773.95565110897;39868.40197397099;40326.1934761309;40402;40761.79619101885;40978.79510602547;39302;39888;39950.60049399469;40126.799366004154;41212.1757564841;26036.739632603432;33217.50173747444;34135.829320853234;36342.45486317654;36349.27301453903;20871.791282086986;26110.86944565265;26207.95168193251;26463.33841653974;26757.197284081507 16:47:22 INFO - PROCESS | 1986 | |2;jquery.html;385.60090306532726;386.1312047892243;391.9637483274391;392.4682055814371;407.8959865234365;13014.284214368206;19636.509087272825;20225.292114775984;20305.18779248832;21221.469463263427;19941.900290498565;20393.49016274594;20512.794872051276;20929;21333.25333613324;5307.601929855257;5355.250264962909;5955.761769529214;5989.131575921489;5997.100434934751;5564.944350556503;5863.560232982522;5891.322497912752;5933.495652869511;5986.191864098357;5700.030994730885;5837.357890632027;5893.705314734268;5914.172015917753;5914.645121292736;6395.584287021329;6476.611403315769;6489.059086432464;6513.869722605521;6528.738850445977;5516.91724624131;5771.740271687784;5804.361520232771;5833.979053665592;5839.94880921434;12548.18436801605;12866.163699359511;13225.206487610774;13248.337583120901;13470.25913574753;1964.145596665455;2136.989315053415;2267.5918334699745;2333.6861656447713;2350.3536527454917;9744.610215591369;9840.36037657557;9891.35706179096;10135.239857010754;10250.538725757284;1827.963440731178;1829.1585870499587;1837.4156479583392;1854.5270955906158;1909.3508207209477;102061;114272.85727142706;117043.41478292554;125302.37348813289;125581.37209313986;9012.909870901338;9155.862662060075;11324.660260192042;11511.712207194887;11579.942100289445;14288.714225715428;14379.496717614833;14441.855581444051;14442.133471991714;14466.27668616565;8271.875921861178;8413.873791893126;8454.87317690235;8549.358798090043;8585.570721463902;8336.374771892133;8397.328213742885;8423.957880210559;8507.021692505441;8519.318454523624;3913.6693524201337;4401.625861801719;4447.755373454426;4518.1415531049;4541.296099104644;1631.7797097391938;1906.3327835257548;1940.7185958036005;1947.9902600486907;1960.5392732707803;739.4010851210537;922.4787994782927;999.8900120986685;1018.6383833739064;1061.1934929453516;19010.61978760417;21067.789322106582;21315;21539.67690484644;21675.78324216769;951.2390087929767;951.9190868776093;980.475445636581;998.0618218874235;1039.4802598700649;11010.669679909615;11207.383593902414;11304.830427543593;11309;11400.315981041163;499989;510736;511813;525888;596800;121442.78557214314;136041;138238;139273;142891;980.8822941247096;1002.2783595811004;1024.774549599087;1027.7790275090892;1028.2442404832443;18484.44546663602;18750.812491874905;18840.34058807935;18944;18960.715589265896;43084;55390.72304638612;55810;56153.43846561482;56272.15591766045;61719.6914015427;80100.59949700214;82648;83199.16800831915;83495;7749.7287594933905;7801.806461881276;7923.564203968837;8150.959245203736;8184.345252379914;12328.321942292901;12430;12623.684407889876;12669.556565520163;12687.80968285495;10649.89350106489;10695.197860160675;10737.731556710833;10940.56237750513;11099.889001109885;9239.491827949258;10304.948475257575;10649.148068154374;10663.200259980687;10738.570457181626;9023.378301160012;11040.944795275973;11248.212625116163;11267.887321127011;11291.887081129084;7463.626818658937;7894.921050789419;8000.319972802145;8003.839923201387;8182.0181578210995;9971.950140249251;11040.668779936615;11120.555177792798;11127.221094523306;11145.2198346115 16:47:22 INFO - PROCESS | 1986 | |3;mootools.html;1335.732853429314;1607.3249235321164;1762.8677849161315;1911.3596945023426;1950.2881448271385;8083.514989100643;8616.698415555456;8627.180417860292;9148.176664100238;9165.908340916589;8146.144654811248;8465.407421480499;8728.170823771747;8786.209241168293;8969.865452018224;2354.6585745066955;2367.443650742074;2384.4873352229265;2393.4203425739015;2399.6040653292275;2352.2943117064897;2379.797717194032;2402.3513651314133;2402.3633737059695;2410.204632471289;2195.8462907596477;2197.6923230747707;2239.0484044281197;2380.119355838342;2385.8210634202505;2352.7176738791454;2364.6216605343147;2374.3945293950105;2381.1070848431837;2390.067873529325;2339.7075365579303;2353.870537120458;2368.8104951604005;2372.8576285422755;2374.299581623425;8954.2836573074;8995.865062024075;9031.096890310982;9042.412243204171;9051.04963978786;4949.76255936016;5067.822626208065;5508.3114610673665;5513.724313784335;5519.475649813261;3105.736012438945;3287.9506807397906;3564.3049605326974;3635.309291234657;3644.781313121221;1033.4625994482826;1044.4934206909643;1045.8797238317616;1047.8952104789537;1049.3179433368296;11723.179377443497;11844.111691623162;11893.940530297466;11912.285262884254;11927.165098443113;4738.431388233434;4787.2209141035;4861.7326047067045;4873.147199240118;4896.387951506062;10025.147862431775;10125.139363154214;10152.746181345377;10162.18702503798;10178.389296642225;5393.406725260218;5404.378496472956;5418.91871621926;5455.263539422207;5489.451054894558;4456.331550267421;4779.3070004849105;4947.233178857283;4948.90102197954;5053.7473126344275;3979.243943650697;4017.598240175959;4037.7577345359373;4120.17596480705;4149.605787450188;1301.212766276408;1311.514739546374;1314.572763851752;1317.9802302965463;1324.9138805977773;958.6548842416724;961.5961296255589;975.6633961283346;985.732297434004;989.6882482018285;5958.225430693982;6069.787557435468;6071.483923866433;6162.291336496361;6163.7842675506145;435.2557127312268;446.67338006121764;446.99776501117935;774.5236679442215;1062.3519653011656;3979.7811120388656;4130.967258185454;4139.958600413957;4155.563665815106;4188.476440444944;10586.629467968583;10661.147108231324;10958.452077396098;10966;10985.560577576967;10712.143028557697;10843.1867609928;10854.45727713611;10865.402402867918;10926;7878.448508604457;8313.792155196048;8321;8434.662613495515;8461.873071904049;5951.702414879325;6126.571140020156;6139.541380049918;6208.255009398901;6317.210348706411;9557.330986830855;9973.30186886911;10216.591336346462;10231.181505479395;10236.590536378462;9003.234725048444;9957.601695932082;10371.533281002525;10388.168946484111;10397.064264216257;6725.293844146294;7123.25205853378;7433.851322973402;7709.653065612173;7849.568273744999;7259.419246460165;8559.74320770378;8605.268552173135;8680.175383338572;8702.781610574651;6460.935390646034;7545.320921117126;7690.423218258765;7713.537187768752;7799.025121859768;6530.281669016309;7481.850362992601;7553.4712570121765;7559.395248380007;7699.499532530368;6248.812535623939;6856.348646878539;7093.787186384417;7172.605506696973;7266.636668166676;4133.91732165361;5063.316452279042;5112.948870511247;5167.069927413102;5179.378474583074;4425.8893527660775;5084.084864724385;5168.276441298146;5206.984637995706;5239.397469291081 16:47:22 INFO - PROCESS | 1986 | |4;prototype.html;301.82946635151137;318.43954639833885;319.9712025917667;321.16657274372994;324.4241471388287;5501.642393244436;5503.394626591082;5511.6968566728865;5531.695756733383;5551.361593416758;4940.110780059588;5462.6995515246645;5464.005637942152;5470.5350045246105;5478.287822583058;3634.909127271826;3931.7051221158385;3966.643002129808;4039.212353591051;4135.669146468275;4070.979645101785;4121.732087414309;4123.917521649565;4124.030852749602;4172.958270417303;3424.777389469689;4096.365063415175;4152.56398078202;4155.293600087977;4165.5834416558255;3584.6415358464205;3654.543182102237;3890.6887449004294;4124.8762537123785;4129.669626429878;3158.7473002159772;3520.489529018278;3525.8765943191866;3549.2723991581665;3564.6791788739138;4384.693071484998;4897.240927656219;4937.333459982892;5363.249145119687;5401.621886467949;4386.276264416368;4540.88647783808;4723.48041715411;4785.353977213066;4789.521047895218;4644.419447569054;4756.881077973044;5342.0885617338645;5516.034693928546;5850.795222167247;3501.8249087545773;3903.687704983623;3911.2568611963634;3911.7457365271166;3933.9606603934167;5140.588752899796;5694.515966142924;6030.4572588467245;6123.8162855114415;6194.8141555752945;3900.297946369624;3951.786999280412;3955.7626542407547;3966;3984.8406063757125;3697.8520859165333;3899.805009749558;4005.499312585927;4019.2363450944226;4030.2141082488633;3958.851932939473;4732.5977291929885;4737.407824021997;4747.765580948978;4761.714297142183;5383.596230282745;5524.861878453071;5730.770769169187;5850.2102216200265;5909.601978862172;1203.6449247472017;1254.3602762591145;1376.3002691675772;1389.9166050037031;1391.9699422427332;778.8598052350632;843.4264700003934;847.1189962438995;858.2061593026425;896.9192772650492;4595.425571803525;4625.034360396885;4647.651426142985;5465.344158700981;5487.945120548823;5319.840404787863;5867.650440398646;5946.8810623787285;5999.610025348338;6017.548683848641;4679.203816088469;4953.7027778332695;5291.550218231417;5301.973490132525;5381.784728610812;2787.4146429250086;2881.616823924528;3100.8294543799857;3134.2634480896972;3153.3220357623222;4836.806527738921;6048.364921683248;6048.455638992512;6171.6297022178815;6271.498280137578;5290.947090529045;5730.770769169187;6036.3360030396625;6173.876522469525;6176.938230617726;5918.378570250148;6136.7852125175405;6212.4408803208835;6216.7824126155365;6262.060690896402;4843.95156048435;5021.924671129863;5915.881682366415;5938.940610594012;6006.669633170218;5155.607985843876;5647.407022262603;5827.912581311199;5859.619124756877;5891.02798038336;4883.60931125502;6149.2313460817395;6200.875982480416;6216.285127210429;6285.402886725754;3372.4772660237704;3554.5556805399324;3933.252681990413;3950.585188555275;4004.2191772604065;5898.439648233411;6049.364816694184;6052.878942421039;6070.423309785563;6083.847903802441;5534.833954981357;5728.742206600629;5896;5930.970345148419;5975.16347711312;4869.415670119608;5131.615128865275;5386.353637563518;5821.446962538552;5871.941280587311;4686.8125274988615;5461.644993075437;5655.208270842003;5712.742926568398;5809.883802324014;5597.216389705526;5736.942630573641;5865.970670146622;5867.647941123546;5904.317269578321;4960.70235785854;5674.559939226172;5736.082226843685;5739.770409183753;5794.855128621818 16:47:22 INFO - PROCESS | 1986 | |5;yui.html;804.7344376355803;809.6275713171945;814.4705941138263;822.8806823010663;841.6086519768306;14621.780673289895;15033.774493382609;15120.319585618647;15274.312655930496;15305.923470382633;13754.381052852621;13989.0907091039;13998;14026.929865350685;14033.15801051935;704.5526090932244;870.1950695606539;878.8549889268279;895.3463971300954;897.1701176411825;893.2050475077186;897.219419105376;897.2911399993997;903.286403741047;908.6183802802839;935.8128374325128;951.6241084771511;951.8286708392486;954.8376775948071;958.4680502321208;936.1293996583175;942.820864035831;943.7121677888241;946.6733976778;948.1466679988066;683.6308393467522;701.0045735056233;702.7645738677498;702.9156501219835;704.7075463682565;14492.782608260884;14619.049761765451;14662.41350345985;14674.559763207015;14688.192149431778;11587.884121158764;13815.378307976165;13829.446822127104;13884.305784710725;13897.0966887152;8938.553072346422;10886.673399797939;11251.36855156006;11478.713032174262;11897.821532677017;3780.8109594520165;3781.4327850822324;3794.829232684536;3812.866549670748;3943.211357728466;84280;85332;87477.6878346819;87816.560917195;88755.55622221912;10836.295640783324;10942.288751231144;10950.507227174796;11078.224524283223;11103.278286911487;21506.784932150793;21593;21716.565668686537;21755.23856665041;21814.89092554527;8021.478603890729;8043.39674524413;8113.7565873023905;8120.6345714443;8186.959065204755;4428.512863585003;4476.888077798081;4543.568361005699;4561.7262964222255;4568.840090596813;3110.1913502489515;3194.2333839878725;3196.0891146023273;3209.1335339458215;3218;623.8222106699569;635.383677832511;640.4556127291858;653.1150291355241;657.3459407889181;500.1705126747761;512.4363200479518;631.8673078653534;686.6360828760764;711.9534284601627;7174.964125179341;9235.861462078074;9274.30442716799;9282.535873206447;9454.48000359973;564.4412032088203;609.3723464831231;657.3098246840798;669.7321071571313;698.598305974067;3459.446488561818;4200.915981680349;4313.245182093121;4319.352097185448;4346.2828633275785;91221.54389228144;99247.50376248217;99419;100637.98724025479;102072.48963755133;85761;86191;86525;89122.87017038622;89519.65720514198;64951;66493.66753166204;67675;70061.2993870065;74383;9165.587548560197;9344.579493922654;9376;9384.100318944631;9468.195203407511;42600.7219783407;43148;43353.99999999874;44530;44640.77679611581;45994.54005459903;58412.53968650818;58707;60627.09059364201;62246.377536224056;12202.877971220529;12211.572594959134;12239.388030598613;12248.87751122513;12479.251244925334;13677.384517696528;13820.032597718064;13911.721765564429;14029.789553156916;14231.349746381295;12314.630561083182;12417.937910310751;12503.187292825936;12742.980561554868;12744.49022039108;12846.807297890353;12897.742045159232;12901;12927.676808079874;12958;11864.703382415508;12142.150049496451;12179.573714919936;12284.570040048555;12380.690482737644;10269.948650257;10386.532606032595;10470.685879423629;10774.569017239222;10856;9013.909860901307;9174.770630734018;9763.121319081316;10470.952904709467;10677.679669609925 16:47:22 INFO - PROCESS | 1986 | __end_tp_report 16:47:22 INFO - PROCESS | 1986 | __start_cc_report 16:47:22 INFO - PROCESS | 1986 | _x_x_mozilla_cycle_collect,411 16:47:22 INFO - PROCESS | 1986 | __end_cc_report 16:47:22 INFO - PROCESS | 1986 | __startTimestamp1464652042813__endTimestamp 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | ------- Summary: start ------- 16:47:22 INFO - PROCESS | 1986 | Number of tests: 6 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | [#0] dojo.html Cycles:165 Average:5800.03 Median:6075.27 stddev:661.07 (10.9%) stddev-sans-first:632.18 16:47:22 INFO - PROCESS | 1986 | Values: 3252.7 3465.9 3495.2 3513.9 3520.9 5829.7 5850.2 5935.9 5939.4 5978.8 5729.4 5872.5 5887.5 5917.7 6015.9 5187.1 5252.8 5263.6 5314.6 5359.4 5291.1 5414.1 5489.5 5493.5 5499.6 4235.6 5227.8 5315.0 5320.9 5357.0 5279.1 5314.4 5323.3 5327.1 5348.9 5235.1 5272.9 5291.2 5314.7 5366.8 4150.0 5647.1 5722.0 5748.3 5753.5 6234.1 6249.0 6259.2 6270.1 6291.9 5304.8 6284.3 6310.7 6311.3 6320.8 4944.4 5824.4 5844.2 5979.3 6006.7 5576.2 5769.7 6134.0 6423.0 6493.9 5512.9 6075.3 6139.5 6298.7 6299.0 5555.9 6131.9 6239.8 6277.7 6323.0 6124.8 6189.8 6224.8 6249.7 6272.5 6263.7 6284.8 6293.0 6307.3 6309.2 4977.6 4986.6 5002.0 5024.2 5051.8 4447.9 4450.2 4451.6 4490.4 4523.1 5779.9 5941.4 5963.1 5966.5 5978.1 6186.8 6213.0 6260.7 6282.3 6319.5 5839.2 5843.6 5844.8 5845.1 5879.8 5401.0 5446.3 5456.5 5463.8 5474.2 6393.1 6403.9 6440.2 6473.4 6599.4 6266.3 6301.5 6311.9 6313.6 6316.3 6269.2 6509.6 6548.7 6559.8 6580.7 6166.3 6181.5 6261.4 6276.1 6306.0 6271.9 6287.1 6293.5 6294.0 6300.5 5639.9 6139.6 6187.9 6200.9 6209.9 6153.7 6231.7 6232.0 6246.9 6257.5 6072.9 6111.1 6130.1 6157.6 6164.1 6088.8 6116.6 6137.0 6190.4 6222.6 6164.8 6166.2 6171.9 6181.1 6186.5 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | [#1] ext.html Cycles:180 Average:70275.78 Median:42239.76 stddev:148019.65 (350.4%) stddev-sans-first:148342.18 16:47:22 INFO - PROCESS | 1986 | Values: 501.0 504.1 518.2 539.7 568.2 39326.2 42176.2 42303.4 42322.8 42356.7 42821.0 42841.6 43696.6 43741.0 43755.3 8280.2 8303.7 9583.3 9660.6 9671.0 9183.5 9594.7 9658.0 9704.0 9730.1 9708.3 9746.1 9751.0 9777.2 9814.4 9658.6 9719.6 9734.6 9761.8 9783.7 9582.3 9586.3 9665.4 9702.0 9727.2 32943.0 40490.0 40599.2 40609.0 40638.2 3188.4 3408.7 3449.1 3494.3 3509.2 31569.0 32341.0 32371.4 33023.5 33442.3 3199.0 3343.6 3413.5 3417.9 3439.8 520622.0 526701.0 532488.0 532708.0 533306.3 58784.8 69232.0 70952.0 71049.9 73465.5 74086.6 74137.0 74185.3 74516.6 74742.6 15196.5 16246.3 17172.9 17200.7 17433.6 12933.4 12968.9 13018.3 13096.5 13249.3 21440.5 21699.9 21726.6 21740.6 21825.3 7639.0 8455.7 8708.0 8795.5 8893.7 974.4 1025.4 1064.1 1132.7 1141.2 26866.7 31129.7 31149.7 31565.5 31909.5 1106.4 1132.6 1155.5 1188.7 1263.6 15872.6 16051.3 16141.0 16322.5 16456.8 626212.0 631693.0 668758.0 674235.0 681867.0 60162.8 60824.0 61763.7 61968.0 62161.4 1208.9 1241.5 1452.5 1455.8 1457.2 30225.8 30321.5 30424.5 30823.8 30921.0 51150.2 51297.0 51764.5 51911.7 51982.5 451115.0 461666.0 463685.0 463710.0 466508.0 43912.0 44087.6 44142.8 44678.9 44707.0 49221.3 49675.8 49880.8 50067.0 50891.2 40051.8 40700.2 41228.0 41326.4 41774.0 39868.4 40326.2 40402.0 40761.8 40978.8 39302.0 39888.0 39950.6 40126.8 41212.2 26036.7 33217.5 34135.8 36342.5 36349.3 20871.8 26110.9 26208.0 26463.3 26757.2 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | [#2] jquery.html Cycles:180 Average:32858.01 Median:11971.69 stddev:89514.37 (747.7%) stddev-sans-first:89732.27 16:47:22 INFO - PROCESS | 1986 | Values: 385.6 386.1 392.0 392.5 407.9 13014.3 19636.5 20225.3 20305.2 21221.5 19941.9 20393.5 20512.8 20929.0 21333.3 5307.6 5355.3 5955.8 5989.1 5997.1 5564.9 5863.6 5891.3 5933.5 5986.2 5700.0 5837.4 5893.7 5914.2 5914.6 6395.6 6476.6 6489.1 6513.9 6528.7 5516.9 5771.7 5804.4 5834.0 5839.9 12548.2 12866.2 13225.2 13248.3 13470.3 1964.1 2137.0 2267.6 2333.7 2350.4 9744.6 9840.4 9891.4 10135.2 10250.5 1828.0 1829.2 1837.4 1854.5 1909.4 102061.0 114272.9 117043.4 125302.4 125581.4 9012.9 9155.9 11324.7 11511.7 11579.9 14288.7 14379.5 14441.9 14442.1 14466.3 8271.9 8413.9 8454.9 8549.4 8585.6 8336.4 8397.3 8424.0 8507.0 8519.3 3913.7 4401.6 4447.8 4518.1 4541.3 1631.8 1906.3 1940.7 1948.0 1960.5 739.4 922.5 999.9 1018.6 1061.2 19010.6 21067.8 21315.0 21539.7 21675.8 951.2 951.9 980.5 998.1 1039.5 11010.7 11207.4 11304.8 11309.0 11400.3 499989.0 510736.0 511813.0 525888.0 596800.0 121442.8 136041.0 138238.0 139273.0 142891.0 980.9 1002.3 1024.8 1027.8 1028.2 18484.4 18750.8 18840.3 18944.0 18960.7 43084.0 55390.7 55810.0 56153.4 56272.2 61719.7 80100.6 82648.0 83199.2 83495.0 7749.7 7801.8 7923.6 8151.0 8184.3 12328.3 12430.0 12623.7 12669.6 12687.8 10649.9 10695.2 10737.7 10940.6 11099.9 9239.5 10304.9 10649.1 10663.2 10738.6 9023.4 11040.9 11248.2 11267.9 11291.9 7463.6 7894.9 8000.3 8003.8 8182.0 9972.0 11040.7 11120.6 11127.2 11145.2 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | [#3] mootools.html Cycles:180 Average:5764.68 Median:4824.48 stddev:3243.44 (67.2%) stddev-sans-first:3235.45 16:47:22 INFO - PROCESS | 1986 | Values: 1335.7 1607.3 1762.9 1911.4 1950.3 8083.5 8616.7 8627.2 9148.2 9165.9 8146.1 8465.4 8728.2 8786.2 8969.9 2354.7 2367.4 2384.5 2393.4 2399.6 2352.3 2379.8 2402.4 2402.4 2410.2 2195.8 2197.7 2239.0 2380.1 2385.8 2352.7 2364.6 2374.4 2381.1 2390.1 2339.7 2353.9 2368.8 2372.9 2374.3 8954.3 8995.9 9031.1 9042.4 9051.0 4949.8 5067.8 5508.3 5513.7 5519.5 3105.7 3288.0 3564.3 3635.3 3644.8 1033.5 1044.5 1045.9 1047.9 1049.3 11723.2 11844.1 11893.9 11912.3 11927.2 4738.4 4787.2 4861.7 4873.1 4896.4 10025.1 10125.1 10152.7 10162.2 10178.4 5393.4 5404.4 5418.9 5455.3 5489.5 4456.3 4779.3 4947.2 4948.9 5053.7 3979.2 4017.6 4037.8 4120.2 4149.6 1301.2 1311.5 1314.6 1318.0 1324.9 958.7 961.6 975.7 985.7 989.7 5958.2 6069.8 6071.5 6162.3 6163.8 435.3 446.7 447.0 774.5 1062.4 3979.8 4131.0 4140.0 4155.6 4188.5 10586.6 10661.1 10958.5 10966.0 10985.6 10712.1 10843.2 10854.5 10865.4 10926.0 7878.4 8313.8 8321.0 8434.7 8461.9 5951.7 6126.6 6139.5 6208.3 6317.2 9557.3 9973.3 10216.6 10231.2 10236.6 9003.2 9957.6 10371.5 10388.2 10397.1 6725.3 7123.3 7433.9 7709.7 7849.6 7259.4 8559.7 8605.3 8680.2 8702.8 6460.9 7545.3 7690.4 7713.5 7799.0 6530.3 7481.9 7553.5 7559.4 7699.5 6248.8 6856.3 7093.8 7172.6 7266.6 4133.9 5063.3 5112.9 5167.1 5179.4 4425.9 5084.1 5168.3 5207.0 5239.4 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | [#4] prototype.html Cycles:180 Average:4647.85 Median:5223.28 stddev:1465.49 (28.1%) stddev-sans-first:1432.83 16:47:22 INFO - PROCESS | 1986 | Values: 301.8 318.4 320.0 321.2 324.4 5501.6 5503.4 5511.7 5531.7 5551.4 4940.1 5462.7 5464.0 5470.5 5478.3 3634.9 3931.7 3966.6 4039.2 4135.7 4071.0 4121.7 4123.9 4124.0 4173.0 3424.8 4096.4 4152.6 4155.3 4165.6 3584.6 3654.5 3890.7 4124.9 4129.7 3158.7 3520.5 3525.9 3549.3 3564.7 4384.7 4897.2 4937.3 5363.2 5401.6 4386.3 4540.9 4723.5 4785.4 4789.5 4644.4 4756.9 5342.1 5516.0 5850.8 3501.8 3903.7 3911.3 3911.7 3934.0 5140.6 5694.5 6030.5 6123.8 6194.8 3900.3 3951.8 3955.8 3966.0 3984.8 3697.9 3899.8 4005.5 4019.2 4030.2 3958.9 4732.6 4737.4 4747.8 4761.7 5383.6 5524.9 5730.8 5850.2 5909.6 1203.6 1254.4 1376.3 1389.9 1392.0 778.9 843.4 847.1 858.2 896.9 4595.4 4625.0 4647.7 5465.3 5487.9 5319.8 5867.7 5946.9 5999.6 6017.5 4679.2 4953.7 5291.6 5302.0 5381.8 2787.4 2881.6 3100.8 3134.3 3153.3 4836.8 6048.4 6048.5 6171.6 6271.5 5290.9 5730.8 6036.3 6173.9 6176.9 5918.4 6136.8 6212.4 6216.8 6262.1 4844.0 5021.9 5915.9 5938.9 6006.7 5155.6 5647.4 5827.9 5859.6 5891.0 4883.6 6149.2 6200.9 6216.3 6285.4 3372.5 3554.6 3933.3 3950.6 4004.2 5898.4 6049.4 6052.9 6070.4 6083.8 5534.8 5728.7 5896.0 5931.0 5975.2 4869.4 5131.6 5386.4 5821.4 5871.9 4686.8 5461.6 5655.2 5712.7 5809.9 5597.2 5736.9 5866.0 5867.6 5904.3 4960.7 5674.6 5736.1 5739.8 5794.9 16:47:22 INFO - PROCESS | 1986 | 16:47:22 INFO - PROCESS | 1986 | [#5] yui.html Cycles:180 Average:18780.24 Median:4552.65 stddev:26420.64 (580.3%) stddev-sans-first:26460.28 16:47:22 INFO - PROCESS | 1986 | Values: 804.7 809.6 814.5 822.9 841.6 14621.8 15033.8 15120.3 15274.3 15305.9 13754.4 13989.1 13998.0 14026.9 14033.2 704.6 870.2 878.9 895.3 897.2 893.2 897.2 897.3 903.3 908.6 935.8 951.6 951.8 954.8 958.5 936.1 942.8 943.7 946.7 948.1 683.6 701.0 702.8 702.9 704.7 14492.8 14619.0 14662.4 14674.6 14688.2 11587.9 13815.4 13829.4 13884.3 13897.1 8938.6 10886.7 11251.4 11478.7 11897.8 3780.8 3781.4 3794.8 3812.9 3943.2 84280.0 85332.0 87477.7 87816.6 88755.6 10836.3 10942.3 10950.5 11078.2 11103.3 21506.8 21593.0 21716.6 21755.2 21814.9 8021.5 8043.4 8113.8 8120.6 8187.0 4428.5 4476.9 4543.6 4561.7 4568.8 3110.2 3194.2 3196.1 3209.1 3218.0 623.8 635.4 640.5 653.1 657.3 500.2 512.4 631.9 686.6 712.0 7175.0 9235.9 9274.3 9282.5 9454.5 564.4 609.4 657.3 669.7 698.6 3459.4 4200.9 4313.2 4319.4 4346.3 91221.5 99247.5 99419.0 100638.0 102072.5 85761.0 86191.0 86525.0 89122.9 89519.7 64951.0 66493.7 67675.0 70061.3 74383.0 9165.6 9344.6 9376.0 9384.1 9468.2 42600.7 43148.0 43354.0 44530.0 44640.8 45994.5 58412.5 58707.0 60627.1 62246.4 12202.9 12211.6 12239.4 12248.9 12479.3 13677.4 13820.0 13911.7 14029.8 14231.3 12314.6 12417.9 12503.2 12743.0 12744.5 12846.8 12897.7 12901.0 12927.7 12958.0 11864.7 12142.2 12179.6 12284.6 12380.7 10269.9 10386.5 10470.7 10774.6 10856.0 9013.9 9174.8 9763.1 10471.0 10677.7 16:47:22 INFO - PROCESS | 1986 | -------- Summary: end -------- 16:47:22 INFO - PROCESS | 1986 | 16:47:23 INFO - TEST-INFO | 1986: exit 0 16:47:23 INFO - TEST-OK | dromaeo_css | took 1112722ms 16:47:23 INFO - TEST-START | kraken 16:47:23 INFO - operating with platform_type : mac_ 16:47:23 INFO - Initialising browser for kraken test... 16:47:23 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmppq1AgK/profile http://localhost:49669/getInfo.html) 16:47:26 INFO - PROCESS | 1991 | __metrics Screen width/height:1600/1200 16:47:26 INFO - PROCESS | 1991 | colorDepth:24 16:47:26 INFO - PROCESS | 1991 | Browser inner width/height: 1024/689 16:47:26 INFO - PROCESS | 1991 | __metrics 16:47:26 ERROR - PROCESS | 1991 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 16:47:27 INFO - TEST-INFO | 1991: exit 0 16:47:27 INFO - Browser initialized. 16:47:27 INFO - Running cycle 1/1 for kraken test... 16:47:27 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmppq1AgK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:39 INFO - PROCESS | 1993 | RSS: Main: 252674048 16:47:39 INFO - PROCESS | 1993 | 16:47:41 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:42 INFO - PROCESS | 1993 | RSS: Main: 265560064 16:47:42 INFO - PROCESS | 1993 | 16:47:45 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-dft) 16:47:46 INFO - PROCESS | 1993 | RSS: Main: 269574144 16:47:46 INFO - PROCESS | 1993 | 16:47:49 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-fft) 16:47:50 INFO - PROCESS | 1993 | RSS: Main: 270123008 16:47:50 INFO - PROCESS | 1993 | 16:47:52 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator) 16:47:52 INFO - PROCESS | 1993 | RSS: Main: 270942208 16:47:52 INFO - PROCESS | 1993 | 16:47:54 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:55 INFO - PROCESS | 1993 | RSS: Main: 271708160 16:47:55 INFO - PROCESS | 1993 | 16:47:58 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom) 16:47:58 INFO - PROCESS | 1993 | RSS: Main: 272261120 16:47:58 INFO - PROCESS | 1993 | 16:48:01 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate) 16:48:02 INFO - PROCESS | 1993 | RSS: Main: 272429056 16:48:02 INFO - PROCESS | 1993 | 16:48:05 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial) 16:48:06 INFO - PROCESS | 1993 | RSS: Main: 272482304 16:48:06 INFO - PROCESS | 1993 | 16:48:07 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:48:08 INFO - PROCESS | 1993 | RSS: Main: 272367616 16:48:08 INFO - PROCESS | 1993 | 16:48:10 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:48:11 INFO - PROCESS | 1993 | RSS: Main: 272117760 16:48:11 INFO - PROCESS | 1993 | 16:48:13 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:48:14 INFO - PROCESS | 1993 | RSS: Main: 272494592 16:48:14 INFO - PROCESS | 1993 | 16:48:17 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:48:17 INFO - PROCESS | 1993 | RSS: Main: 272637952 16:48:17 INFO - PROCESS | 1993 | 16:48:20 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:48:21 INFO - PROCESS | 1993 | RSS: Main: 272924672 16:48:21 INFO - PROCESS | 1993 | 16:48:22 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49669/tests/kraken/driver.html?testName=ai-astar) 16:48:23 INFO - PROCESS | 1993 | RSS: Main: 273604608 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | __start_tp_report 16:48:23 INFO - PROCESS | 1993 | _x_x_mozilla_page_load 16:48:23 INFO - PROCESS | 1993 | _x_x_mozilla_page_load_details 16:48:23 INFO - PROCESS | 1993 | |i|pagename|runs| 16:48:23 INFO - PROCESS | 1993 | |0;ai-astar;114;110;116;116;115;114;118;114;116;117 16:48:23 INFO - PROCESS | 1993 | |1;audio-beat-detection;156;113;184;196;141;145;136;130;156;137 16:48:23 INFO - PROCESS | 1993 | |2;audio-dft;196;200;200;208;260;239;195;196;197;195 16:48:23 INFO - PROCESS | 1993 | |3;audio-fft;70;80;74;142;57;58;58;63;128;58 16:48:23 INFO - PROCESS | 1993 | |4;audio-oscillator;70;72;115;75;100;113;152;67;72;114 16:48:23 INFO - PROCESS | 1993 | |5;imaging-gaussian-blur;92;85;88;84;90;86;88;89;89;86 16:48:23 INFO - PROCESS | 1993 | |6;imaging-darkroom;94;96;95;113;153;125;95;99;95;95 16:48:23 INFO - PROCESS | 1993 | |7;imaging-desaturate;112;146;147;119;143;138;83;137;76;81 16:48:23 INFO - PROCESS | 1993 | |8;json-parse-financial;55;57;57;62;78;75;57;52;47;79 16:48:23 INFO - PROCESS | 1993 | |9;json-stringify-tinderbox;55;57;81;106;63;113;115;103;82;71 16:48:23 INFO - PROCESS | 1993 | |10;stanford-crypto-aes;89;89;91;86;86;98;110;76;160;114 16:48:23 INFO - PROCESS | 1993 | |11;stanford-crypto-ccm;137;139;142;138;140;170;161;143;149;142 16:48:23 INFO - PROCESS | 1993 | |12;stanford-crypto-pbkdf2;167;168;166;164;171;162;166;171;166;160 16:48:23 INFO - PROCESS | 1993 | |13;stanford-crypto-sha256-iterative;63;90;89;57;76;83;88;87;86;83 16:48:23 INFO - PROCESS | 1993 | __end_tp_report 16:48:23 INFO - PROCESS | 1993 | __start_cc_report 16:48:23 INFO - PROCESS | 1993 | _x_x_mozilla_cycle_collect,1080 16:48:23 INFO - PROCESS | 1993 | __end_cc_report 16:48:23 INFO - PROCESS | 1993 | __startTimestamp1464652103160__endTimestamp 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | ------- Summary: start ------- 16:48:23 INFO - PROCESS | 1993 | Number of tests: 14 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:2.21 (1.9%) stddev-sans-first:2.32 16:48:23 INFO - PROCESS | 1993 | Values: 114.0 110.0 116.0 116.0 115.0 114.0 118.0 114.0 116.0 117.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:150.50 stddev:24.90 (16.5%) stddev-sans-first:26.30 16:48:23 INFO - PROCESS | 1993 | Values: 156.0 113.0 184.0 196.0 141.0 145.0 136.0 130.0 156.0 137.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#2] audio-dft Cycles:10 Average:208.60 Median:200.00 stddev:22.45 (11.2%) stddev-sans-first:23.35 16:48:23 INFO - PROCESS | 1993 | Values: 196.0 200.0 200.0 208.0 260.0 239.0 195.0 196.0 197.0 195.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#3] audio-fft Cycles:10 Average:78.80 Median:60.50 stddev:30.80 (50.9%) stddev-sans-first:32.51 16:48:23 INFO - PROCESS | 1993 | Values: 70.0 80.0 74.0 142.0 57.0 58.0 58.0 63.0 128.0 58.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:68.50 stddev:28.34 (41.4%) stddev-sans-first:28.57 16:48:23 INFO - PROCESS | 1993 | Values: 70.0 72.0 115.0 75.0 100.0 113.0 152.0 67.0 72.0 114.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:2.45 (2.8%) stddev-sans-first:2.05 16:48:23 INFO - PROCESS | 1993 | Values: 92.0 85.0 88.0 84.0 90.0 86.0 88.0 89.0 89.0 86.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:95.00 stddev:19.43 (20.4%) stddev-sans-first:20.11 16:48:23 INFO - PROCESS | 1993 | Values: 94.0 96.0 95.0 113.0 153.0 125.0 95.0 99.0 95.0 95.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#7] imaging-desaturate Cycles:10 Average:118.20 Median:146.50 stddev:28.69 (19.6%) stddev-sans-first:30.34 16:48:23 INFO - PROCESS | 1993 | Values: 112.0 146.0 147.0 119.0 143.0 138.0 83.0 137.0 76.0 81.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:59.50 stddev:11.37 (19.1%) stddev-sans-first:11.78 16:48:23 INFO - PROCESS | 1993 | Values: 55.0 57.0 57.0 62.0 78.0 75.0 57.0 52.0 47.0 79.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#9] json-stringify-tinderbox Cycles:10 Average:84.60 Median:60.00 stddev:23.18 (38.6%) stddev-sans-first:21.97 16:48:23 INFO - PROCESS | 1993 | Values: 55.0 57.0 81.0 106.0 63.0 113.0 115.0 103.0 82.0 71.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#10] stanford-crypto-aes Cycles:10 Average:99.90 Median:87.50 stddev:24.02 (27.4%) stddev-sans-first:25.15 16:48:23 INFO - PROCESS | 1993 | Values: 89.0 89.0 91.0 86.0 86.0 98.0 110.0 76.0 160.0 114.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:142.50 stddev:10.96 (7.7%) stddev-sans-first:11.12 16:48:23 INFO - PROCESS | 1993 | Values: 137.0 139.0 142.0 138.0 140.0 170.0 161.0 143.0 149.0 142.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:3.51 (2.1%) stddev-sans-first:3.71 16:48:23 INFO - PROCESS | 1993 | Values: 167.0 168.0 166.0 164.0 171.0 162.0 166.0 171.0 166.0 160.0 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - PROCESS | 1993 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.20 Median:86.50 stddev:11.46 (13.2%) stddev-sans-first:10.33 16:48:23 INFO - PROCESS | 1993 | Values: 63.0 90.0 89.0 57.0 76.0 83.0 88.0 87.0 86.0 83.0 16:48:23 INFO - PROCESS | 1993 | -------- Summary: end -------- 16:48:23 INFO - PROCESS | 1993 | 16:48:23 INFO - TEST-INFO | 1993: exit 0 16:48:23 INFO - TEST-OK | kraken | took 60601ms 16:48:23 INFO - SUITE-END | took 1173s 16:48:24 INFO - Completed test suite (00:19:33) 16:48:24 INFO - javascript benchmark 16:48:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7333.779203377039, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.7072563469287, 3465.8613655453782, 3495.1961048958747, 3513.8594456221754, 3520.894373168804, 5829.70851457427, 5850.2394688690465, 5935.881282374361, 5939.435753603411, 5978.790742324019, 5729.39841316662, 5872.530197584194, 5887.470127688507, 5917.73370198341, 6015.879682406349, 5187.1181899077155, 5252.842414727565, 5263.552598029153, 5314.627976041678, 5359.356877174744, 5291.126964050927, 5414.052540805363, 5489.478499542557, 5493.478119578634, 5499.560035197174, 4235.618794308496, 5227.8170264040955, 5314.97342513285, 5320.866978325554, 5357.008953343625, 5279.102552566034, 5314.415414304423, 5323.307969963918, 5327.094393953037, 5348.91976620351, 5235.110031294651, 5272.947270527284, 5291.1534154535475, 5314.654547454403, 5366.758495867656, 4149.958500415018, 5647.124695672177, 5722.0, 5748.338941021794, 5753.453421924953, 6234.064890266451, 6249.000159974382, 6259.217597800275, 6270.059491076329, 6291.937080629181, 5304.814331498417, 6284.308726040132, 6310.716017779165, 6311.305756366796, 6320.841978950563, 4944.431390390115, 5824.417558244184, 5844.211031510734, 5979.342272350038, 6006.6696331701305, 5576.219329293821, 5769.652286539819, 6134.00801433197, 6422.9780620480005, 6493.935060649427, 5512.889742205213, 6075.27096748393, 6139.508839292846, 6298.685065746694, 6298.968505157536, 5555.888822235488, 6131.908021379683, 6239.78160764371, 6277.717502712298, 6323.019931910561, 6124.846878828065, 6189.81430557084, 6224.751009959551, 6249.6875156242, 6272.466840318532, 6263.68681565929, 6284.842878928063, 6293.0, 6307.337729538424, 6309.242890853127, 4977.601791856643, 4986.601071914238, 5001.974990125026, 5024.2463630454995, 5051.797928082836, 4447.888802779957, 4450.221211288012, 4451.577100175478, 4490.438695163105, 4523.095380923828, 5779.884402312015, 5941.405859414111, 5963.075723262901, 5966.522678185735, 5978.103284507359, 6186.752529898844, 6212.968935155295, 6260.655663938436, 6282.277538083087, 6319.494440444753, 5839.153322768259, 5843.561732870052, 5844.795432159854, 5845.064789633639, 5879.823605291848, 5401.0, 5446.346438427414, 5456.508914197741, 5463.808766693147, 5474.233607294982, 6393.136926514954, 6403.903941440882, 6440.227172739301, 6473.417392434703, 6599.406053455212, 6266.27937787142, 6301.4643755279485, 6311.936880631135, 6313.55805093639, 6316.273628532777, 6269.153664255267, 6509.576877502947, 6548.672566371567, 6559.8032059038305, 6580.670966451754, 6166.260048794174, 6181.505479561534, 6261.373862613702, 6276.1213430118905, 6306.0225665022, 6271.874562508633, 6287.119803227461, 6293.527985401021, 6293.96853015732, 6300.527460440394, 5639.943600563942, 6139.6454669416935, 6187.876242475035, 6200.937990620036, 6209.875802484015, 6153.66154861469, 6231.688415579293, 6231.9688401559515, 6246.937530624636, 6257.53068519872, 6072.90890636632, 6111.113888486234, 6130.080487926846, 6157.568970172045, 6164.137020816999, 6088.817335479943, 6116.579253789288, 6137.009281402767, 6190.380961903774, 6222.564420490522, 6164.815055548341, 6166.167567378526, 6171.9074213887725, 6181.103739957591, 6186.5360097993735], "value": 5759.653307921757, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.0280056690021, 504.13289142674597, 518.1657531374486, 539.719345940111, 568.2186992884783, 39326.21347573047, 42176.15647687044, 42303.36544951824, 42322.788386058026, 42356.72929812107, 42821.0, 42841.571584284226, 43696.56303436965, 43740.99999999992, 43755.34366984498, 8280.171982801701, 8303.709370172046, 9583.329166958316, 9660.613575456973, 9670.9516452418, 9183.49490778007, 9594.712158635251, 9658.0, 9704.0, 9730.053989202153, 9708.320417570772, 9746.122848943594, 9750.951245243763, 9777.217822574177, 9814.411135331868, 9658.613655453773, 9719.61121555137, 9734.610615575368, 9761.804763904753, 9783.706488805346, 9582.28132890036, 9586.328956973015, 9665.371750836173, 9702.029797020312, 9727.173190278834, 32943.0, 40490.0, 40599.18801623981, 40608.98477538056, 40638.18723625511, 3188.4260833050043, 3408.659134086596, 3449.120474279068, 3494.3239533627584, 3509.2279698466295, 31569.0, 32341.0, 32371.439141694394, 33023.50464743031, 33442.3311533768, 3199.0, 3343.565336506263, 3413.4879768034743, 3417.8632854685784, 3439.7764145330725, 520622.0, 526701.0, 532488.0, 532708.0, 533306.333468334, 58784.82430351369, 69231.96152057723, 70952.0, 71049.93425098628, 73465.53068938591, 74086.62956685181, 74137.0, 74185.25814741892, 74516.62741686366, 74742.62628686822, 15196.468123615621, 16246.268917898722, 17172.914135429244, 17200.741988870177, 17433.56416089608, 12933.417996190194, 12968.870311296954, 13018.349082545836, 13096.541621043218, 13249.271290078945, 21440.463988400104, 21699.89150054271, 21726.565468690536, 21740.56518869614, 21825.345239642837, 7639.0, 8455.731640254011, 8707.956460217785, 8795.47227166372, 8893.688720894737, 974.4445665970329, 1025.4411345816548, 1064.1380481809758, 1132.6885106595753, 1141.2068612314363, 26866.731332686813, 31129.68870311268, 31149.688503114678, 31565.526517102262, 31909.52135717966, 1106.446776611694, 1132.6375559820942, 1155.526234243956, 1188.732535179595, 1263.6272299671618, 15872.603184920239, 16051.277692503865, 16141.03153810775, 16322.477171273762, 16456.83543164553, 626212.0, 631693.0, 668758.0, 674235.0, 681867.0, 60162.79674406487, 60824.0, 61763.6911815447, 61968.0, 62161.378386216464, 1208.9214201076902, 1241.4785789968234, 1452.4843680493273, 1455.7670772676533, 1457.176695167227, 30225.848870756385, 30321.545176821925, 30424.543631845096, 30823.845880770452, 30921.0, 51150.23274650809, 51296.97406051784, 51764.48235517597, 51911.74044129755, 51982.480175197765, 451115.0, 461666.0, 463685.0, 463710.0, 466508.0, 43912.0, 44087.55912440835, 44142.77928610337, 44678.883027923264, 44707.0, 49221.261681074095, 49675.75810604764, 49880.750596246784, 50067.02612529382, 50891.2366314513, 40051.79974100111, 40700.1859962805, 41228.0, 41326.38010429906, 41773.95565110897, 39868.40197397099, 40326.1934761309, 40402.0, 40761.79619101885, 40978.79510602547, 39302.0, 39888.0, 39950.60049399469, 40126.799366004154, 41212.1757564841, 26036.739632603432, 33217.50173747444, 34135.829320853234, 36342.45486317654, 36349.27301453903, 20871.791282086986, 26110.86944565265, 26207.95168193251, 26463.33841653974, 26757.197284081507], "value": 20952.500792598603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.60090306532726, 386.1312047892243, 391.9637483274391, 392.4682055814371, 407.8959865234365, 13014.284214368206, 19636.509087272825, 20225.292114775984, 20305.18779248832, 21221.469463263427, 19941.900290498565, 20393.49016274594, 20512.794872051276, 20929.0, 21333.25333613324, 5307.601929855257, 5355.250264962909, 5955.761769529214, 5989.131575921489, 5997.100434934751, 5564.944350556503, 5863.560232982522, 5891.322497912752, 5933.495652869511, 5986.191864098357, 5700.030994730885, 5837.357890632027, 5893.705314734268, 5914.172015917753, 5914.645121292736, 6395.584287021329, 6476.611403315769, 6489.059086432464, 6513.869722605521, 6528.738850445977, 5516.91724624131, 5771.740271687784, 5804.361520232771, 5833.979053665592, 5839.94880921434, 12548.18436801605, 12866.163699359511, 13225.206487610774, 13248.337583120901, 13470.25913574753, 1964.145596665455, 2136.989315053415, 2267.5918334699745, 2333.6861656447713, 2350.3536527454917, 9744.610215591369, 9840.36037657557, 9891.35706179096, 10135.239857010754, 10250.538725757284, 1827.963440731178, 1829.1585870499587, 1837.4156479583392, 1854.5270955906158, 1909.3508207209477, 102061.0, 114272.85727142706, 117043.41478292554, 125302.37348813289, 125581.37209313986, 9012.909870901338, 9155.862662060075, 11324.660260192042, 11511.712207194887, 11579.942100289445, 14288.714225715428, 14379.496717614833, 14441.855581444051, 14442.133471991714, 14466.27668616565, 8271.875921861178, 8413.873791893126, 8454.87317690235, 8549.358798090043, 8585.570721463902, 8336.374771892133, 8397.328213742885, 8423.957880210559, 8507.021692505441, 8519.318454523624, 3913.6693524201337, 4401.625861801719, 4447.755373454426, 4518.1415531049, 4541.296099104644, 1631.7797097391938, 1906.3327835257548, 1940.7185958036005, 1947.9902600486907, 1960.5392732707803, 739.4010851210537, 922.4787994782927, 999.8900120986685, 1018.6383833739064, 1061.1934929453516, 19010.61978760417, 21067.789322106582, 21315.0, 21539.67690484644, 21675.78324216769, 951.2390087929767, 951.9190868776093, 980.475445636581, 998.0618218874235, 1039.4802598700649, 11010.669679909615, 11207.383593902414, 11304.830427543593, 11309.0, 11400.315981041163, 499989.0, 510736.0, 511813.0, 525888.0, 596800.0, 121442.78557214314, 136041.0, 138238.0, 139273.0, 142891.0, 980.8822941247096, 1002.2783595811004, 1024.774549599087, 1027.7790275090892, 1028.2442404832443, 18484.44546663602, 18750.812491874905, 18840.34058807935, 18944.0, 18960.715589265896, 43084.0, 55390.72304638612, 55810.0, 56153.43846561482, 56272.15591766045, 61719.6914015427, 80100.59949700214, 82648.0, 83199.16800831915, 83495.0, 7749.7287594933905, 7801.806461881276, 7923.564203968837, 8150.959245203736, 8184.345252379914, 12328.321942292901, 12430.0, 12623.684407889876, 12669.556565520163, 12687.80968285495, 10649.89350106489, 10695.197860160675, 10737.731556710833, 10940.56237750513, 11099.889001109885, 9239.491827949258, 10304.948475257575, 10649.148068154374, 10663.200259980687, 10738.570457181626, 9023.378301160012, 11040.944795275973, 11248.212625116163, 11267.887321127011, 11291.887081129084, 7463.626818658937, 7894.921050789419, 8000.319972802145, 8003.839923201387, 8182.0181578210995, 9971.950140249251, 11040.668779936615, 11120.555177792798, 11127.221094523306, 11145.2198346115], "value": 9323.40298739068, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.732853429314, 1607.3249235321164, 1762.8677849161315, 1911.3596945023426, 1950.2881448271385, 8083.514989100643, 8616.698415555456, 8627.180417860292, 9148.176664100238, 9165.908340916589, 8146.144654811248, 8465.407421480499, 8728.170823771747, 8786.209241168293, 8969.865452018224, 2354.6585745066955, 2367.443650742074, 2384.4873352229265, 2393.4203425739015, 2399.6040653292275, 2352.2943117064897, 2379.797717194032, 2402.3513651314133, 2402.3633737059695, 2410.204632471289, 2195.8462907596477, 2197.6923230747707, 2239.0484044281197, 2380.119355838342, 2385.8210634202505, 2352.7176738791454, 2364.6216605343147, 2374.3945293950105, 2381.1070848431837, 2390.067873529325, 2339.7075365579303, 2353.870537120458, 2368.8104951604005, 2372.8576285422755, 2374.299581623425, 8954.2836573074, 8995.865062024075, 9031.096890310982, 9042.412243204171, 9051.04963978786, 4949.76255936016, 5067.822626208065, 5508.3114610673665, 5513.724313784335, 5519.475649813261, 3105.736012438945, 3287.9506807397906, 3564.3049605326974, 3635.309291234657, 3644.781313121221, 1033.4625994482826, 1044.4934206909643, 1045.8797238317616, 1047.8952104789537, 1049.3179433368296, 11723.179377443497, 11844.111691623162, 11893.940530297466, 11912.285262884254, 11927.165098443113, 4738.431388233434, 4787.2209141035, 4861.7326047067045, 4873.147199240118, 4896.387951506062, 10025.147862431775, 10125.139363154214, 10152.746181345377, 10162.18702503798, 10178.389296642225, 5393.406725260218, 5404.378496472956, 5418.91871621926, 5455.263539422207, 5489.451054894558, 4456.331550267421, 4779.3070004849105, 4947.233178857283, 4948.90102197954, 5053.7473126344275, 3979.243943650697, 4017.598240175959, 4037.7577345359373, 4120.17596480705, 4149.605787450188, 1301.212766276408, 1311.514739546374, 1314.572763851752, 1317.9802302965463, 1324.9138805977773, 958.6548842416724, 961.5961296255589, 975.6633961283346, 985.732297434004, 989.6882482018285, 5958.225430693982, 6069.787557435468, 6071.483923866433, 6162.291336496361, 6163.7842675506145, 435.2557127312268, 446.67338006121764, 446.99776501117935, 774.5236679442215, 1062.3519653011656, 3979.7811120388656, 4130.967258185454, 4139.958600413957, 4155.563665815106, 4188.476440444944, 10586.629467968583, 10661.147108231324, 10958.452077396098, 10966.0, 10985.560577576967, 10712.143028557697, 10843.1867609928, 10854.45727713611, 10865.402402867918, 10926.0, 7878.448508604457, 8313.792155196048, 8321.0, 8434.662613495515, 8461.873071904049, 5951.702414879325, 6126.571140020156, 6139.541380049918, 6208.255009398901, 6317.210348706411, 9557.330986830855, 9973.30186886911, 10216.591336346462, 10231.181505479395, 10236.590536378462, 9003.234725048444, 9957.601695932082, 10371.533281002525, 10388.168946484111, 10397.064264216257, 6725.293844146294, 7123.25205853378, 7433.851322973402, 7709.653065612173, 7849.568273744999, 7259.419246460165, 8559.74320770378, 8605.268552173135, 8680.175383338572, 8702.781610574651, 6460.935390646034, 7545.320921117126, 7690.423218258765, 7713.537187768752, 7799.025121859768, 6530.281669016309, 7481.850362992601, 7553.4712570121765, 7559.395248380007, 7699.499532530368, 6248.812535623939, 6856.348646878539, 7093.787186384417, 7172.605506696973, 7266.636668166676, 4133.91732165361, 5063.316452279042, 5112.948870511247, 5167.069927413102, 5179.378474583074, 4425.8893527660775, 5084.084864724385, 5168.276441298146, 5206.984637995706, 5239.397469291081], "value": 4597.816670606883, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.82946635151137, 318.43954639833885, 319.9712025917667, 321.16657274372994, 324.4241471388287, 5501.642393244436, 5503.394626591082, 5511.6968566728865, 5531.695756733383, 5551.361593416758, 4940.110780059588, 5462.6995515246645, 5464.005637942152, 5470.5350045246105, 5478.287822583058, 3634.909127271826, 3931.7051221158385, 3966.643002129808, 4039.212353591051, 4135.669146468275, 4070.979645101785, 4121.732087414309, 4123.917521649565, 4124.030852749602, 4172.958270417303, 3424.777389469689, 4096.365063415175, 4152.56398078202, 4155.293600087977, 4165.5834416558255, 3584.6415358464205, 3654.543182102237, 3890.6887449004294, 4124.8762537123785, 4129.669626429878, 3158.7473002159772, 3520.489529018278, 3525.8765943191866, 3549.2723991581665, 3564.6791788739138, 4384.693071484998, 4897.240927656219, 4937.333459982892, 5363.249145119687, 5401.621886467949, 4386.276264416368, 4540.88647783808, 4723.48041715411, 4785.353977213066, 4789.521047895218, 4644.419447569054, 4756.881077973044, 5342.0885617338645, 5516.034693928546, 5850.795222167247, 3501.8249087545773, 3903.687704983623, 3911.2568611963634, 3911.7457365271166, 3933.9606603934167, 5140.588752899796, 5694.515966142924, 6030.4572588467245, 6123.8162855114415, 6194.8141555752945, 3900.297946369624, 3951.786999280412, 3955.7626542407547, 3966.0, 3984.8406063757125, 3697.8520859165333, 3899.805009749558, 4005.499312585927, 4019.2363450944226, 4030.2141082488633, 3958.851932939473, 4732.5977291929885, 4737.407824021997, 4747.765580948978, 4761.714297142183, 5383.596230282745, 5524.861878453071, 5730.770769169187, 5850.2102216200265, 5909.601978862172, 1203.6449247472017, 1254.3602762591145, 1376.3002691675772, 1389.9166050037031, 1391.9699422427332, 778.8598052350632, 843.4264700003934, 847.1189962438995, 858.2061593026425, 896.9192772650492, 4595.425571803525, 4625.034360396885, 4647.651426142985, 5465.344158700981, 5487.945120548823, 5319.840404787863, 5867.650440398646, 5946.8810623787285, 5999.610025348338, 6017.548683848641, 4679.203816088469, 4953.7027778332695, 5291.550218231417, 5301.973490132525, 5381.784728610812, 2787.4146429250086, 2881.616823924528, 3100.8294543799857, 3134.2634480896972, 3153.3220357623222, 4836.806527738921, 6048.364921683248, 6048.455638992512, 6171.6297022178815, 6271.498280137578, 5290.947090529045, 5730.770769169187, 6036.3360030396625, 6173.876522469525, 6176.938230617726, 5918.378570250148, 6136.7852125175405, 6212.4408803208835, 6216.7824126155365, 6262.060690896402, 4843.95156048435, 5021.924671129863, 5915.881682366415, 5938.940610594012, 6006.669633170218, 5155.607985843876, 5647.407022262603, 5827.912581311199, 5859.619124756877, 5891.02798038336, 4883.60931125502, 6149.2313460817395, 6200.875982480416, 6216.285127210429, 6285.402886725754, 3372.4772660237704, 3554.5556805399324, 3933.252681990413, 3950.585188555275, 4004.2191772604065, 5898.439648233411, 6049.364816694184, 6052.878942421039, 6070.423309785563, 6083.847903802441, 5534.833954981357, 5728.742206600629, 5896.0, 5930.970345148419, 5975.16347711312, 4869.415670119608, 5131.615128865275, 5386.353637563518, 5821.446962538552, 5871.941280587311, 4686.8125274988615, 5461.644993075437, 5655.208270842003, 5712.742926568398, 5809.883802324014, 5597.216389705526, 5736.942630573641, 5865.970670146622, 5867.647941123546, 5904.317269578321, 4960.70235785854, 5674.559939226172, 5736.082226843685, 5739.770409183753, 5794.855128621818], "value": 4177.905982740837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.7344376355803, 809.6275713171945, 814.4705941138263, 822.8806823010663, 841.6086519768306, 14621.780673289895, 15033.774493382609, 15120.319585618647, 15274.312655930496, 15305.923470382633, 13754.381052852621, 13989.0907091039, 13998.0, 14026.929865350685, 14033.15801051935, 704.5526090932244, 870.1950695606539, 878.8549889268279, 895.3463971300954, 897.1701176411825, 893.2050475077186, 897.219419105376, 897.2911399993997, 903.286403741047, 908.6183802802839, 935.8128374325128, 951.6241084771511, 951.8286708392486, 954.8376775948071, 958.4680502321208, 936.1293996583175, 942.820864035831, 943.7121677888241, 946.6733976778, 948.1466679988066, 683.6308393467522, 701.0045735056233, 702.7645738677498, 702.9156501219835, 704.7075463682565, 14492.782608260884, 14619.049761765451, 14662.41350345985, 14674.559763207015, 14688.192149431778, 11587.884121158764, 13815.378307976165, 13829.446822127104, 13884.305784710725, 13897.0966887152, 8938.553072346422, 10886.673399797939, 11251.36855156006, 11478.713032174262, 11897.821532677017, 3780.8109594520165, 3781.4327850822324, 3794.829232684536, 3812.866549670748, 3943.211357728466, 84280.0, 85332.0, 87477.6878346819, 87816.560917195, 88755.55622221912, 10836.295640783324, 10942.288751231144, 10950.507227174796, 11078.224524283223, 11103.278286911487, 21506.784932150793, 21593.0, 21716.565668686537, 21755.23856665041, 21814.89092554527, 8021.478603890729, 8043.39674524413, 8113.7565873023905, 8120.6345714443, 8186.959065204755, 4428.512863585003, 4476.888077798081, 4543.568361005699, 4561.7262964222255, 4568.840090596813, 3110.1913502489515, 3194.2333839878725, 3196.0891146023273, 3209.1335339458215, 3218.0, 623.8222106699569, 635.383677832511, 640.4556127291858, 653.1150291355241, 657.3459407889181, 500.1705126747761, 512.4363200479518, 631.8673078653534, 686.6360828760764, 711.9534284601627, 7174.964125179341, 9235.861462078074, 9274.30442716799, 9282.535873206447, 9454.48000359973, 564.4412032088203, 609.3723464831231, 657.3098246840798, 669.7321071571313, 698.598305974067, 3459.446488561818, 4200.915981680349, 4313.245182093121, 4319.352097185448, 4346.2828633275785, 91221.54389228144, 99247.50376248217, 99419.0, 100637.98724025479, 102072.48963755133, 85761.0, 86191.0, 86525.0, 89122.87017038622, 89519.65720514198, 64951.0, 66493.66753166204, 67675.0, 70061.2993870065, 74383.0, 9165.587548560197, 9344.579493922654, 9376.0, 9384.100318944631, 9468.195203407511, 42600.7219783407, 43148.0, 43353.99999999874, 44530.0, 44640.77679611581, 45994.54005459903, 58412.53968650818, 58707.0, 60627.09059364201, 62246.377536224056, 12202.877971220529, 12211.572594959134, 12239.388030598613, 12248.87751122513, 12479.251244925334, 13677.384517696528, 13820.032597718064, 13911.721765564429, 14029.789553156916, 14231.349746381295, 12314.630561083182, 12417.937910310751, 12503.187292825936, 12742.980561554868, 12744.49022039108, 12846.807297890353, 12897.742045159232, 12901.0, 12927.676808079874, 12958.0, 11864.703382415508, 12142.150049496451, 12179.573714919936, 12284.570040048555, 12380.690482737644, 10269.948650257, 10386.532606032595, 10470.685879423629, 10774.569017239222, 10856.0, 9013.909860901307, 9174.770630734018, 9763.121319081316, 10470.952904709467, 10677.679669609925], "value": 7197.867920683611, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 116.0, 116.0, 115.0, 114.0, 118.0, 114.0, 116.0, 117.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 113.0, 184.0, 196.0, 141.0, 145.0, 136.0, 130.0, 156.0, 137.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 200.0, 208.0, 260.0, 239.0, 195.0, 196.0, 197.0, 195.0], "value": 208.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 80.0, 74.0, 142.0, 57.0, 58.0, 58.0, 63.0, 128.0, 58.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 115.0, 75.0, 100.0, 113.0, 152.0, 67.0, 72.0, 114.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 88.0, 84.0, 90.0, 86.0, 88.0, 89.0, 89.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 113.0, 153.0, 125.0, 95.0, 99.0, 95.0, 95.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 146.0, 147.0, 119.0, 143.0, 138.0, 83.0, 137.0, 76.0, 81.0], "value": 118.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 57.0, 62.0, 78.0, 75.0, 57.0, 52.0, 47.0, 79.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 81.0, 106.0, 63.0, 113.0, 115.0, 103.0, 82.0, 71.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 91.0, 86.0, 86.0, 98.0, 110.0, 76.0, 160.0, 114.0], "value": 99.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 142.0, 138.0, 140.0, 170.0, 161.0, 143.0, 149.0, 142.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 166.0, 164.0, 171.0, 162.0, 166.0, 171.0, 166.0, 160.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 90.0, 89.0, 57.0, 76.0, 83.0, 88.0, 87.0, 86.0, 83.0], "value": 80.2, "unit": "score"}]}]} 16:48:24 INFO - Return code: 0 16:48:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:48:24 ERROR - # TBPL SUCCESS # 16:48:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:48:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:48:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:48:24 INFO - 2016-05-30 16:48:24.471 screenresolution[1998:13894] starting screenresolution argv=screenresolution get 16:48:24 INFO - 2016-05-30 16:48:24.474 screenresolution[1998:13894] Display 0: 1600x1200x32@60 16:48:24 INFO - 2016-05-30 16:48:24.480 screenresolution[1999:13897] starting screenresolution argv=screenresolution list 16:48:24 INFO - Available Modes on Display 0 16:48:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:48:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:48:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:48:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:48:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:48:24 INFO - Intel Iris: 16:48:24 INFO - Chipset Model: Intel Iris 16:48:24 INFO - Type: GPU 16:48:24 INFO - Bus: Built-In 16:48:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:48:24 INFO - Vendor: Intel (0x8086) 16:48:24 INFO - Device ID: 0x0a2e 16:48:24 INFO - Revision ID: 0x0009 16:48:24 INFO - Displays: 16:48:24 INFO - Display: 16:48:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:48:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:48:24 INFO - Display Serial Number: 1600x1200 60 16:48:24 INFO - Main Display: Yes 16:48:24 INFO - Mirror: Off 16:48:24 INFO - Online: Yes 16:48:24 INFO - Rotation: Supported 16:48:24 INFO - Return code: 0 16:48:24 INFO - Running post-action listener: _resource_record_post_action 16:48:24 INFO - ##### 16:48:24 INFO - ##### Finished run-tests step (success) 16:48:24 INFO - ##### 16:48:24 INFO - Running post-run listener: _resource_record_post_run 16:48:24 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 131072; Write bytes: 699264000; Read time: 3; Write time: 1969 16:48:24 INFO - TinderboxPrint: CPU usage
54.4% 16:48:24 INFO - TinderboxPrint: I/O read bytes / time
131,072 / 3 16:48:24 INFO - TinderboxPrint: I/O write bytes / time
699,264,000 / 1,969 16:48:24 INFO - TinderboxPrint: CPU idle
2,206.3 (45.6%) 16:48:24 INFO - TinderboxPrint: CPU system
975.0 (20.2%) 16:48:24 INFO - TinderboxPrint: CPU user
1,656.4 (34.2%) 16:48:24 INFO - TinderboxPrint: Swap in / out
398,630,912 / 0 16:48:24 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399919616; Write bytes: 401802752; Read time: 29812; Write time: 862 16:48:24 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 131072; Write bytes: 296613376; Read time: 3; Write time: 1103 16:48:25 INFO - Running post-run listener: _upload_blobber_files 16:48:25 INFO - Blob upload gear active. 16:48:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:25 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 16:48:25 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'] 16:48:25 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 16:48:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:48:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:48:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:48:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:26 INFO - (blobuploader) - INFO - Done attempting. 16:48:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:48:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:48:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:27 INFO - (blobuploader) - INFO - Done attempting. 16:48:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:48:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:30 INFO - (blobuploader) - INFO - Done attempting. 16:48:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:30 INFO - Return code: 0 16:48:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b70b4bbca50bcb026b0a370077c408938d32cfcc249587e3b2e945391533bce5dcf15fb8fefb3c241155cab122b6735d1d262cf17d0ea20e1cb7d11183247f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/641f76154121c9c37f1c693e1982d2c1df6ab91786d6fc5f0ed2cfec771c1594ada55ff0eab698bb1abaa2bc43a653762ce1e084ef197e2190a1f8a10973a244", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a53dba75fc986e6b488a1810ff987871b6e57e36732f8bb85b80a1055593fcae501d103cb39796e7fd1c21954c033ac7e28626f5dcb906eecc570ad7edf2f7b9"} 16:48:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:30 INFO - Contents: 16:48:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b70b4bbca50bcb026b0a370077c408938d32cfcc249587e3b2e945391533bce5dcf15fb8fefb3c241155cab122b6735d1d262cf17d0ea20e1cb7d11183247f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/641f76154121c9c37f1c693e1982d2c1df6ab91786d6fc5f0ed2cfec771c1594ada55ff0eab698bb1abaa2bc43a653762ce1e084ef197e2190a1f8a10973a244", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a53dba75fc986e6b488a1810ff987871b6e57e36732f8bb85b80a1055593fcae501d103cb39796e7fd1c21954c033ac7e28626f5dcb906eecc570ad7edf2f7b9"} 16:48:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:30 INFO - Copying logs to upload dir... 16:48:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1333.641219 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-30 16:48:30.101742) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:48:30.105269) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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/b70b4bbca50bcb026b0a370077c408938d32cfcc249587e3b2e945391533bce5dcf15fb8fefb3c241155cab122b6735d1d262cf17d0ea20e1cb7d11183247f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/641f76154121c9c37f1c693e1982d2c1df6ab91786d6fc5f0ed2cfec771c1594ada55ff0eab698bb1abaa2bc43a653762ce1e084ef197e2190a1f8a10973a244", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a53dba75fc986e6b488a1810ff987871b6e57e36732f8bb85b80a1055593fcae501d103cb39796e7fd1c21954c033ac7e28626f5dcb906eecc570ad7edf2f7b9"} build_url:https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011099 build_url: 'https://queue.taskcluster.net/v1/task/L5O6lzNTR8q1L_eyq9X6BA/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/b70b4bbca50bcb026b0a370077c408938d32cfcc249587e3b2e945391533bce5dcf15fb8fefb3c241155cab122b6735d1d262cf17d0ea20e1cb7d11183247f0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/641f76154121c9c37f1c693e1982d2c1df6ab91786d6fc5f0ed2cfec771c1594ada55ff0eab698bb1abaa2bc43a653762ce1e084ef197e2190a1f8a10973a244", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a53dba75fc986e6b488a1810ff987871b6e57e36732f8bb85b80a1055593fcae501d103cb39796e7fd1c21954c033ac7e28626f5dcb906eecc570ad7edf2f7b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:48:30.138553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:48:30.138877) ========= 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.Xu3SA2YZqE/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.caA2MhArkd/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.005072 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:48:30.187405) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:48:30.187696) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:48:30.188036) ========= ========= Total master_lag: 0.19 =========