builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0251 starttime: 1463623318.46 results: success (0) buildid: 20160518172845 builduid: 213804cc4b3045a08e311bdc6ec89854 revision: 5bd03d0a58768dbe3184fa9f001372291bd679b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:58.458682) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:58.459114) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:58.459406) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:58.479148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:58.479543) ========= 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.9MIeQmMBe6/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.xWCI71XdWj/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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-18 19:01:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.36M=0.002s 2016-05-18 19:01:58 (7.36 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.556692 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:59.057155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:59.057492) ========= 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.9MIeQmMBe6/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.xWCI71XdWj/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.020529 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:01:59.107045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 19:01:59.107472) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5bd03d0a58768dbe3184fa9f001372291bd679b8 --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 5bd03d0a58768dbe3184fa9f001372291bd679b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9MIeQmMBe6/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.xWCI71XdWj/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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-18 19:01:59,139 truncating revision to first 12 chars 2016-05-18 19:01:59,139 Setting DEBUG logging. 2016-05-18 19:01:59,139 attempt 1/10 2016-05-18 19:01:59,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5bd03d0a5876?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 19:02:00,300 unpacking tar archive at: fx-team-5bd03d0a5876/testing/mozharness/ program finished with exit code 0 elapsedTime=1.414489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-18 19:02:00.539400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 19:02:00.539759) ========= script_repo_revision: 5bd03d0a58768dbe3184fa9f001372291bd679b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 19:02:00.540175) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 19:02:00.540442) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 19:02:00.554380) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-05-18 19:02:00.554811) ========= /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.9MIeQmMBe6/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.xWCI71XdWj/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:02:00 INFO - MultiFileLogger online at 20160518 19:02:00 in /builds/slave/test 19:02:00 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 19:02:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:00 INFO - {'append_to_log': False, 19:02:00 INFO - 'base_work_dir': '/builds/slave/test', 19:02:00 INFO - 'blob_upload_branch': 'Fx-Team', 19:02:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:00 INFO - 'branch': 'Fx-Team', 19:02:00 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:00 INFO - 'config_files': ('talos/mac_config.py',), 19:02:00 INFO - 'default_actions': ('clobber', 19:02:00 INFO - 'read-buildbot-config', 19:02:00 INFO - 'download-and-extract', 19:02:00 INFO - 'populate-webroot', 19:02:00 INFO - 'create-virtualenv', 19:02:00 INFO - 'install', 19:02:00 INFO - 'run-tests'), 19:02:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:00 INFO - 'download_minidump_stackwalk': True, 19:02:00 INFO - 'download_symbols': 'ondemand', 19:02:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:00 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:00 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:00 INFO - 'installer_path': 'installer.exe', 19:02:00 INFO - 'log_level': 'info', 19:02:00 INFO - 'log_name': 'talos', 19:02:00 INFO - 'log_to_console': True, 19:02:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:02:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:02:00 INFO - 'opt_config_files': (), 19:02:00 INFO - 'pip_index': False, 19:02:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:00 INFO - 'cmd': ('bash', 19:02:00 INFO - '-c', 19:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:00 INFO - 'enabled': True, 19:02:00 INFO - 'halt_on_failure': False, 19:02:00 INFO - 'name': 'check_screen_resolution'},), 19:02:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:00 INFO - 'cmd': ('bash', 19:02:00 INFO - '-c', 19:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:00 INFO - 'enabled': True, 19:02:00 INFO - 'halt_on_failure': False, 19:02:00 INFO - 'name': 'check_screen_resolution'},), 19:02:00 INFO - 'run_cmd_checks_enabled': True, 19:02:00 INFO - 'sps_profile': False, 19:02:00 INFO - 'sps_profile_interval': 0, 19:02:00 INFO - 'suite': 'dromaeojs-e10s', 19:02:00 INFO - 'system_bits': '32', 19:02:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:00 INFO - 'title': 't-yosemite-r7-0251', 19:02:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:00 INFO - 'use_talos_json': True, 19:02:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:00 INFO - 'work_dir': 'build'} 19:02:00 INFO - ##### 19:02:00 INFO - ##### Running clobber step. 19:02:00 INFO - ##### 19:02:00 INFO - Running pre-action listener: _resource_record_pre_action 19:02:00 INFO - Running main action method: clobber 19:02:00 INFO - rmtree: /builds/slave/test/build 19:02:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:02 INFO - Running post-action listener: _resource_record_post_action 19:02:02 INFO - ##### 19:02:02 INFO - ##### Finished clobber step (success) 19:02:02 INFO - ##### 19:02:02 INFO - ##### 19:02:02 INFO - ##### Running read-buildbot-config step. 19:02:02 INFO - ##### 19:02:02 INFO - Running pre-action listener: _resource_record_pre_action 19:02:02 INFO - Running main action method: read_buildbot_config 19:02:02 INFO - Using buildbot properties: 19:02:02 INFO - { 19:02:02 INFO - "project": "", 19:02:02 INFO - "product": "firefox", 19:02:02 INFO - "script_repo_revision": "production", 19:02:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 19:02:02 INFO - "repository": "", 19:02:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 19:02:02 INFO - "buildid": "20160518172845", 19:02:02 INFO - "pgo_build": "False", 19:02:02 INFO - "basedir": "/builds/slave/test", 19:02:02 INFO - "buildnumber": 8, 19:02:02 INFO - "slavename": "t-yosemite-r7-0251", 19:02:02 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:02:02 INFO - "platform": "yosemite_r7", 19:02:02 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 19:02:02 INFO - "branch": "fx-team", 19:02:02 INFO - "repo_path": "integration/fx-team", 19:02:02 INFO - "revision": "5bd03d0a58768dbe3184fa9f001372291bd679b8", 19:02:02 INFO - "stage_platform": "macosx64", 19:02:02 INFO - "builduid": "213804cc4b3045a08e311bdc6ec89854", 19:02:02 INFO - "slavebuilddir": "test" 19:02:02 INFO - } 19:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:02:02 INFO - Running post-action listener: _resource_record_post_action 19:02:02 INFO - ##### 19:02:02 INFO - ##### Finished read-buildbot-config step (success) 19:02:02 INFO - ##### 19:02:02 INFO - ##### 19:02:02 INFO - ##### Running download-and-extract step. 19:02:02 INFO - ##### 19:02:02 INFO - Running pre-action listener: _resource_record_pre_action 19:02:02 INFO - Running main action method: download_and_extract 19:02:02 INFO - mkdir: /builds/slave/test/build/tests 19:02:02 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')]} 19:02:02 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:02:32 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 19:02:32 INFO - retry: attempt #1 caught exception: timed out 19:02:32 INFO - retry: Failed, sleeping 30 seconds before retrying 19:03:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:03 INFO - Downloaded 1199 bytes. 19:03:03 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:03:03 INFO - Using the following test package requirements: 19:03:03 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:03:03 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:03:03 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:03:03 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'jsshell-mac.zip'], 19:03:03 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:03:03 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:03:03 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:03:03 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:03:03 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:03:03 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:03:03 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:03:03 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:03:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:03 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:03:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:03:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:03:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:05 INFO - Downloaded 18628945 bytes. 19:03:05 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'] 19:03:05 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 19:03:08 INFO - Return code: 0 19:03:08 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 19:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:08 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:03:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:03:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:03:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:09 INFO - Downloaded 18628945 bytes. 19:03:09 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'] 19:03:09 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 19:03:13 INFO - Return code: 0 19:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:13 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:03:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:03:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:03:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:14 INFO - Downloaded 10840194 bytes. 19:03:14 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'] 19:03:14 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 19:03:14 INFO - Return code: 0 19:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:14 INFO - https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:03:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:03:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:03:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:03:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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 19:03:18 INFO - Downloaded 110307193 bytes. 19:03:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:03:18 INFO - mkdir: /builds/slave/test/properties 19:03:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:18 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:18 INFO - Contents: 19:03:18 INFO - build_url:https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:03:19 INFO - Running post-action listener: _resource_record_post_action 19:03:19 INFO - Running post-action listener: set_extra_try_arguments 19:03:19 INFO - ##### 19:03:19 INFO - ##### Finished download-and-extract step (success) 19:03:19 INFO - ##### 19:03:19 INFO - ##### 19:03:19 INFO - ##### Running populate-webroot step. 19:03:19 INFO - ##### 19:03:19 INFO - Running pre-action listener: _resource_record_pre_action 19:03:19 INFO - Running main action method: populate_webroot 19:03:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:03:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:03:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:03:19 INFO - '--tppagecycles', 19:03:19 INFO - '7'], 19:03:19 INFO - 'tests': ['tsvgm']}}, 19:03:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:03:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:03:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['damp', 'tps']}, 19:03:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'tests': ['damp', 'tps']}, 19:03:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['dromaeo_dom']}, 19:03:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['a11yr', 19:03:19 INFO - 'ts_paint', 19:03:19 INFO - 'tpaint', 19:03:19 INFO - 'sessionrestore', 19:03:19 INFO - 'sessionrestore_no_auto_restore', 19:03:19 INFO - 'tabpaint']}, 19:03:19 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:19 INFO - 'ts_paint', 19:03:19 INFO - 'tpaint', 19:03:19 INFO - 'sessionrestore', 19:03:19 INFO - 'sessionrestore_no_auto_restore', 19:03:19 INFO - 'tabpaint']}, 19:03:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:03:19 INFO - 'ts_paint', 19:03:19 INFO - 'tpaint', 19:03:19 INFO - 'sessionrestore', 19:03:19 INFO - 'sessionrestore_no_auto_restore', 19:03:19 INFO - 'tabpaint']}, 19:03:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:03:19 INFO - 'ts_paint', 19:03:19 INFO - 'tpaint', 19:03:19 INFO - 'sessionrestore', 19:03:19 INFO - 'sessionrestore_no_auto_restore', 19:03:19 INFO - 'tabpaint']}, 19:03:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['a11yr', 19:03:19 INFO - 'ts_paint', 19:03:19 INFO - 'tpaint', 19:03:19 INFO - 'sessionrestore', 19:03:19 INFO - 'sessionrestore_no_auto_restore', 19:03:19 INFO - 'tabpaint']}, 19:03:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['a11yr', 19:03:19 INFO - 'ts_paint', 19:03:19 INFO - 'tpaint', 19:03:19 INFO - 'sessionrestore', 19:03:19 INFO - 'sessionrestore_no_auto_restore', 19:03:19 INFO - 'tabpaint']}, 19:03:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['tsvgx', 19:03:19 INFO - 'tsvgr_opacity', 19:03:19 INFO - 'tart', 19:03:19 INFO - 'tscrollx', 19:03:19 INFO - 'cart']}, 19:03:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:19 INFO - 'tsvgr_opacity', 19:03:19 INFO - 'tart', 19:03:19 INFO - 'tscrollx', 19:03:19 INFO - 'cart']}, 19:03:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'talos_options': ['--disable-e10s'], 19:03:19 INFO - 'tests': ['tp5o']}, 19:03:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'tests': ['tp5o']}, 19:03:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'talos_options': ['--disable-e10s', 19:03:19 INFO - '--xperf_path', 19:03:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:19 INFO - 'tests': ['tp5n']}, 19:03:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:19 INFO - 'talos_options': ['--xperf_path', 19:03:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:19 INFO - 'tests': ['tp5n']}}, 19:03:19 INFO - 'talos.zip': {'path': '', 19:03:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:03:19 INFO - Running post-action listener: _resource_record_post_action 19:03:19 INFO - ##### 19:03:19 INFO - ##### Finished populate-webroot step (success) 19:03:19 INFO - ##### 19:03:19 INFO - ##### 19:03:19 INFO - ##### Running create-virtualenv step. 19:03:19 INFO - ##### 19:03:19 INFO - Running pre-action listener: _resource_record_pre_action 19:03:19 INFO - Running main action method: create_virtualenv 19:03:19 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:19 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 19:03:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:20 INFO - Using real prefix '/tools/python27' 19:03:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:20 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:23 INFO - Installing pip.................done. 19:03:23 INFO - Return code: 0 19:03:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:23 INFO - 'HOME': '/Users/cltbld', 19:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:23 INFO - 'LOGNAME': 'cltbld', 19:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:23 INFO - 'MOZ_NO_REMOTE': '1', 19:03:23 INFO - 'NO_EM_RESTART': '1', 19:03:23 INFO - 'PAGER': '/bin/cat', 19:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:23 INFO - 'PWD': '/builds/slave/test', 19:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:23 INFO - 'SHELL': '/bin/bash', 19:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:23 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:23 INFO - 'USER': 'cltbld', 19:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:23 INFO - 'XPC_FLAGS': '0x0', 19:03:23 INFO - 'XPC_SERVICE_NAME': '0', 19:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:23 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 19:03:23 INFO - Cleaning up... 19:03:23 INFO - Return code: 0 19:03:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:23 INFO - 'HOME': '/Users/cltbld', 19:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:23 INFO - 'LOGNAME': 'cltbld', 19:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:23 INFO - 'MOZ_NO_REMOTE': '1', 19:03:23 INFO - 'NO_EM_RESTART': '1', 19:03:23 INFO - 'PAGER': '/bin/cat', 19:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:23 INFO - 'PWD': '/builds/slave/test', 19:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:23 INFO - 'SHELL': '/bin/bash', 19:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:23 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:23 INFO - 'USER': 'cltbld', 19:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:23 INFO - 'XPC_FLAGS': '0x0', 19:03:23 INFO - 'XPC_SERVICE_NAME': '0', 19:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:24 INFO - Downloading/unpacking psutil>=3.1.1 19:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:03:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:27 INFO - Installing collected packages: psutil 19:03:27 INFO - Running setup.py install for psutil 19:03:27 INFO - building 'psutil._psutil_osx' extension 19:03:27 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 19:03:28 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 19:03:28 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 19:03:28 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 19:03:28 INFO - building 'psutil._psutil_posix' extension 19:03:28 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 19:03:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:03:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:03:28 INFO - ^ 19:03:28 INFO - 1 warning generated. 19:03:28 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 19:03:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:28 INFO - Successfully installed psutil 19:03:28 INFO - Cleaning up... 19:03:28 INFO - Return code: 0 19:03:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:03: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')]} 19:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03: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')]} 19:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:28 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:28 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 19:03:28 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 19:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:28 INFO - 'HOME': '/Users/cltbld', 19:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:28 INFO - 'LOGNAME': 'cltbld', 19:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:28 INFO - 'MOZ_NO_REMOTE': '1', 19:03:28 INFO - 'NO_EM_RESTART': '1', 19:03:28 INFO - 'PAGER': '/bin/cat', 19:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:28 INFO - 'PWD': '/builds/slave/test', 19:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:28 INFO - 'SHELL': '/bin/bash', 19:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:28 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:28 INFO - 'USER': 'cltbld', 19:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:28 INFO - 'XPC_FLAGS': '0x0', 19:03:28 INFO - 'XPC_SERVICE_NAME': '0', 19:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:03:31 INFO - Installing collected packages: mozsystemmonitor 19:03:31 INFO - Running setup.py install for mozsystemmonitor 19:03:31 INFO - Successfully installed mozsystemmonitor 19:03:31 INFO - Cleaning up... 19:03:31 INFO - Return code: 0 19:03:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:03:31 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')]} 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:31 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')]} 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:31 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:31 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 19:03:31 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 19:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:31 INFO - 'HOME': '/Users/cltbld', 19:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:31 INFO - 'LOGNAME': 'cltbld', 19:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:31 INFO - 'MOZ_NO_REMOTE': '1', 19:03:31 INFO - 'NO_EM_RESTART': '1', 19:03:31 INFO - 'PAGER': '/bin/cat', 19:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:31 INFO - 'PWD': '/builds/slave/test', 19:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:31 INFO - 'SHELL': '/bin/bash', 19:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:31 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:31 INFO - 'USER': 'cltbld', 19:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:31 INFO - 'XPC_FLAGS': '0x0', 19:03:31 INFO - 'XPC_SERVICE_NAME': '0', 19:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - Downloading blobuploader-1.2.4.tar.gz 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:03:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:03:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 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 19:03:34 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 19:03:35 INFO - Downloading docopt-0.6.1.tar.gz 19:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:03:35 INFO - Installing collected packages: blobuploader, requests, docopt 19:03:35 INFO - Running setup.py install for blobuploader 19:03:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:03:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:03:35 INFO - Running setup.py install for requests 19:03:36 INFO - Running setup.py install for docopt 19:03:36 INFO - Successfully installed blobuploader requests docopt 19:03:36 INFO - Cleaning up... 19:03:36 INFO - Return code: 0 19:03:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:36 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')]} 19:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:36 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')]} 19:03:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:36 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:36 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 19:03:36 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 19:03:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:36 INFO - 'HOME': '/Users/cltbld', 19:03:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:36 INFO - 'LOGNAME': 'cltbld', 19:03:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:36 INFO - 'MOZ_NO_REMOTE': '1', 19:03:36 INFO - 'NO_EM_RESTART': '1', 19:03:36 INFO - 'PAGER': '/bin/cat', 19:03:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:36 INFO - 'PWD': '/builds/slave/test', 19:03:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:36 INFO - 'SHELL': '/bin/bash', 19:03:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:36 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:36 INFO - 'USER': 'cltbld', 19:03:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:36 INFO - 'XPC_FLAGS': '0x0', 19:03:36 INFO - 'XPC_SERVICE_NAME': '0', 19:03:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:36 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-c0SZQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:36 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-6GVHLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:36 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-uBrBn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:36 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-96qh84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-UmNC0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-YY0NNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-0XEfKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-rxZJBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-GCVzZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-4EE7w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-672xHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-7qh6p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:37 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-vn1DUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:38 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-W2twcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:38 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-s256cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:38 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-LsSWSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:38 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-5amk0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:03:38 INFO - Running setup.py install for manifestparser 19:03:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Running setup.py install for mozcrash 19:03:38 INFO - Running setup.py install for mozdebug 19:03:38 INFO - Running setup.py install for mozdevice 19:03:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozfile 19:03:39 INFO - Running setup.py install for mozhttpd 19:03:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozinfo 19:03:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozInstall 19:03:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozleak 19:03:39 INFO - Running setup.py install for mozlog 19:03:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for moznetwork 19:03:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Running setup.py install for mozprocess 19:03:40 INFO - Running setup.py install for mozprofile 19:03:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Running setup.py install for mozrunner 19:03:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Running setup.py install for mozscreenshot 19:03:40 INFO - Running setup.py install for moztest 19:03:40 INFO - Running setup.py install for mozversion 19:03:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:03:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:03:41 INFO - Cleaning up... 19:03:41 INFO - Return code: 0 19:03:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:41 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')]} 19:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:41 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')]} 19:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:41 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:41 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 19:03:41 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 19:03:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:41 INFO - 'HOME': '/Users/cltbld', 19:03:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:41 INFO - 'LOGNAME': 'cltbld', 19:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:41 INFO - 'MOZ_NO_REMOTE': '1', 19:03:41 INFO - 'NO_EM_RESTART': '1', 19:03:41 INFO - 'PAGER': '/bin/cat', 19:03:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:41 INFO - 'PWD': '/builds/slave/test', 19:03:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:41 INFO - 'SHELL': '/bin/bash', 19:03:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:41 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:41 INFO - 'USER': 'cltbld', 19:03:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:41 INFO - 'XPC_FLAGS': '0x0', 19:03:41 INFO - 'XPC_SERVICE_NAME': '0', 19:03:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:41 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8U3VkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:41 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Ytfyq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:41 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-qVmxJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:41 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-7STHZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:41 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jsVtSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:41 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-sxrv4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:41 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-yEqw8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-2xtg6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-BdjKE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-PEkl4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-3X42aM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-U_t4Oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-d0Hw0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-GTLgt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-o3hxiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:42 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-o3gAWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:43 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)) 19:03:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:43 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-JeoNeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:03:43 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 19:03:43 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 19:03:43 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 19:03:43 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 19:03:43 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 19:03:43 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 19:03:45 INFO - Downloading blessings-1.6.tar.gz 19:03:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:03:45 INFO - Installing collected packages: blessings 19:03:45 INFO - Running setup.py install for blessings 19:03:45 INFO - Successfully installed blessings 19:03:45 INFO - Cleaning up... 19:03:46 INFO - Return code: 0 19:03:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:03:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:46 INFO - Reading from file tmpfile_stdout 19:03:46 INFO - Current package versions: 19:03:46 INFO - blessings == 1.6 19:03:46 INFO - blobuploader == 1.2.4 19:03:46 INFO - docopt == 0.6.1 19:03:46 INFO - manifestparser == 1.1 19:03:46 INFO - mozInstall == 1.12 19:03:46 INFO - mozcrash == 0.17 19:03:46 INFO - mozdebug == 0.1 19:03:46 INFO - mozdevice == 0.48 19:03:46 INFO - mozfile == 1.2 19:03:46 INFO - mozhttpd == 0.7 19:03:46 INFO - mozinfo == 0.9 19:03:46 INFO - mozleak == 0.1 19:03:46 INFO - mozlog == 3.2 19:03:46 INFO - moznetwork == 0.27 19:03:46 INFO - mozprocess == 0.22 19:03:46 INFO - mozprofile == 0.28 19:03:46 INFO - mozrunner == 6.11 19:03:46 INFO - mozscreenshot == 0.1 19:03:46 INFO - mozsystemmonitor == 0.3 19:03:46 INFO - moztest == 0.7 19:03:46 INFO - mozversion == 1.4 19:03:46 INFO - psutil == 3.1.1 19:03:46 INFO - requests == 1.2.3 19:03:46 INFO - wsgiref == 0.1.2 19:03:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:46 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')]} 19:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 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')]} 19:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:46 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 19:03:46 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 19:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:46 INFO - 'HOME': '/Users/cltbld', 19:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:46 INFO - 'LOGNAME': 'cltbld', 19:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:46 INFO - 'MOZ_NO_REMOTE': '1', 19:03:46 INFO - 'NO_EM_RESTART': '1', 19:03:46 INFO - 'PAGER': '/bin/cat', 19:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:46 INFO - 'PWD': '/builds/slave/test', 19:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:46 INFO - 'SHELL': '/bin/bash', 19:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:46 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:46 INFO - 'USER': 'cltbld', 19:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:46 INFO - 'XPC_FLAGS': '0x0', 19:03:46 INFO - 'XPC_SERVICE_NAME': '0', 19:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 INFO - Cleaning up... 19:03:46 INFO - Return code: 0 19:03:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:03:46 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')]} 19:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 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')]} 19:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:46 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 19:03:46 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 19:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:46 INFO - 'HOME': '/Users/cltbld', 19:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:46 INFO - 'LOGNAME': 'cltbld', 19:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:46 INFO - 'MOZ_NO_REMOTE': '1', 19:03:46 INFO - 'NO_EM_RESTART': '1', 19:03:46 INFO - 'PAGER': '/bin/cat', 19:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:46 INFO - 'PWD': '/builds/slave/test', 19:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:46 INFO - 'SHELL': '/bin/bash', 19:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:46 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:46 INFO - 'USER': 'cltbld', 19:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:46 INFO - 'XPC_FLAGS': '0x0', 19:03:46 INFO - 'XPC_SERVICE_NAME': '0', 19:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:46 INFO - Downloading/unpacking jsonschema 19:03:46 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 19:03:46 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 19:03:46 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 19:03:46 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 19:03:46 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 19:03:46 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 19:03:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:03:49 INFO - Downloading/unpacking functools32 (from jsonschema) 19:03: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 19:03: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 19:03: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 19:03: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 19:03: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 19:03: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 19:03:49 INFO - Downloading functools32-3.2.3-2.tar.gz 19:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:03:49 INFO - warning: no files found matching '*.txt' 19:03:49 INFO - no previously-included directories found matching 'build' 19:03:49 INFO - no previously-included directories found matching 'dist' 19:03:49 INFO - no previously-included directories found matching '.git*' 19:03:49 INFO - Installing collected packages: jsonschema, functools32 19:03:49 INFO - Running setup.py install for functools32 19:03:49 INFO - warning: no files found matching '*.txt' 19:03:49 INFO - no previously-included directories found matching 'build' 19:03:49 INFO - no previously-included directories found matching 'dist' 19:03:49 INFO - no previously-included directories found matching '.git*' 19:03:49 INFO - Successfully installed jsonschema functools32 19:03:49 INFO - Cleaning up... 19:03:50 INFO - Return code: 0 19:03:50 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:50 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 0x106910a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106916570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8503c95d30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, '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.9MIeQmMBe6/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.xWCI71XdWj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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 19:03:50 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 19:03:50 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 19:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:50 INFO - 'HOME': '/Users/cltbld', 19:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:50 INFO - 'LOGNAME': 'cltbld', 19:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:50 INFO - 'MOZ_NO_REMOTE': '1', 19:03:50 INFO - 'NO_EM_RESTART': '1', 19:03:50 INFO - 'PAGER': '/bin/cat', 19:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:50 INFO - 'PWD': '/builds/slave/test', 19:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:50 INFO - 'SHELL': '/bin/bash', 19:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:03:50 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:50 INFO - 'USER': 'cltbld', 19:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:50 INFO - 'XPC_FLAGS': '0x0', 19:03:50 INFO - 'XPC_SERVICE_NAME': '0', 19:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:50 INFO - Downloading/unpacking flake8 19:03:50 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 19:03:50 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 19:03:50 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 19:03:50 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 19:03:50 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 19:03:50 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 19:03:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:03:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:03: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 19:03: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 19:03: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 19:03: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 19:03: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 19:03: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 19:03:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:03:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 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 19:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:03:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:03:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:03:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:03:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:03:54 INFO - no previously-included directories found matching 'docs/_build' 19:03:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:03:54 INFO - Running setup.py install for pep8 19:03:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:03:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:03:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:03:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:03:54 INFO - no previously-included directories found matching 'docs/_build' 19:03:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:03:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:03:54 INFO - Cleaning up... 19:03:54 INFO - Return code: 0 19:03:54 INFO - Running post-action listener: _resource_record_post_action 19:03:54 INFO - Running post-action listener: _start_resource_monitoring 19:03:54 INFO - Starting resource monitoring. 19:03:54 INFO - ##### 19:03:54 INFO - ##### Finished create-virtualenv step (success) 19:03:54 INFO - ##### 19:03:54 INFO - ##### 19:03:54 INFO - ##### Running install step. 19:03:54 INFO - ##### 19:03:54 INFO - Running pre-action listener: _resource_record_pre_action 19:03:54 INFO - Running main action method: install 19:03:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:54 INFO - Reading from file tmpfile_stdout 19:03:54 INFO - Detecting whether we're running mozinstall >=1.0... 19:03:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:03:54 INFO - Reading from file tmpfile_stdout 19:03:54 INFO - Output received: 19:03:54 INFO - Usage: mozinstall [options] installer 19:03:54 INFO - Options: 19:03:54 INFO - -h, --help show this help message and exit 19:03:54 INFO - -d DEST, --destination=DEST 19:03:54 INFO - Directory to install application into. [default: 19:03:54 INFO - "/builds/slave/test"] 19:03:54 INFO - --app=APP Application being installed. [default: firefox] 19:03:54 INFO - mkdir: /builds/slave/test/build/application 19:03:54 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'] 19:03:54 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 19:04:28 INFO - Reading from file tmpfile_stdout 19:04:28 INFO - Output received: 19:04:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:04:28 INFO - Running post-action listener: _resource_record_post_action 19:04:28 INFO - ##### 19:04:28 INFO - ##### Finished install step (success) 19:04:28 INFO - ##### 19:04:28 INFO - ##### 19:04:28 INFO - ##### Running run-tests step. 19:04:28 INFO - ##### 19:04:28 INFO - Running pre-action listener: _resource_record_pre_action 19:04:28 INFO - Running main action method: run_tests 19:04:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:04:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:04:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:04:28 INFO - 2016-05-18 19:04:28.843 screenresolution[2840:33253] starting screenresolution argv=screenresolution get 19:04:28 INFO - 2016-05-18 19:04:28.846 screenresolution[2840:33253] Display 0: 1600x1200x32@60 19:04:28 INFO - 2016-05-18 19:04:28.853 screenresolution[2841:33256] starting screenresolution argv=screenresolution list 19:04:28 INFO - Available Modes on Display 0 19:04:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:04:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:04:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:04:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:04:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:04:29 INFO - Intel Iris: 19:04:29 INFO - Chipset Model: Intel Iris 19:04:29 INFO - Type: GPU 19:04:29 INFO - Bus: Built-In 19:04:29 INFO - VRAM (Dynamic, Max): 1536 MB 19:04:29 INFO - Vendor: Intel (0x8086) 19:04:29 INFO - Device ID: 0x0a2e 19:04:29 INFO - Revision ID: 0x0009 19:04:29 INFO - Displays: 19:04:29 INFO - Display: 19:04:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:04:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:04:29 INFO - Display Serial Number: 1600x1200 60 19:04:29 INFO - Main Display: Yes 19:04:29 INFO - Mirror: Off 19:04:29 INFO - Online: Yes 19:04:29 INFO - Rotation: Supported 19:04:29 INFO - Return code: 0 19:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:04:29 INFO - Python 2.7.3 19:04:29 INFO - Return code: 0 19:04:29 INFO - grabbing minidump binary from tooltool 19:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:29 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 0x1067b4d30>, '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 0x1065d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1067abb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:04:29 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 19:04:29 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 19:04:29 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 19:04:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:04:29 INFO - Return code: 0 19:04:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:04:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:04:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:04:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:04:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:04:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:04:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:04:30 INFO - Return code: 0 19:04:30 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/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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 19:04:30 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/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --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 19:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9MIeQmMBe6/Render', 19:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:30 INFO - 'HOME': '/Users/cltbld', 19:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:30 INFO - 'LOGNAME': 'cltbld', 19:04:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:30 INFO - 'MOZ_NO_REMOTE': '1', 19:04:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:30 INFO - 'NO_EM_RESTART': '1', 19:04:30 INFO - 'PAGER': '/bin/cat', 19:04:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:30 INFO - 'PWD': '/builds/slave/test', 19:04:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:30 INFO - 'SHELL': '/bin/bash', 19:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xWCI71XdWj/Listeners', 19:04:30 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 19:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:30 INFO - 'USER': 'cltbld', 19:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:30 INFO - 'XPC_FLAGS': '0x0', 19:04:30 INFO - 'XPC_SERVICE_NAME': '0', 19:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:30 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/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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 19:04:30 INFO - mozversion application_buildid: 20160518172845 19:04:30 INFO - mozversion application_changeset: 5bd03d0a58768dbe3184fa9f001372291bd679b8 19:04:30 INFO - mozversion application_display_name: Nightly 19:04:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:04:30 INFO - mozversion application_name: Firefox 19:04:30 INFO - mozversion application_remotingname: firefox 19:04:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:04:30 INFO - mozversion application_vendor: Mozilla 19:04:30 INFO - mozversion application_version: 49.0a1 19:04:30 INFO - mozversion platform_buildid: 20160518172845 19:04:30 INFO - mozversion platform_changeset: 5bd03d0a58768dbe3184fa9f001372291bd679b8 19:04:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:04:30 INFO - mozversion platform_version: 49.0a1 19:04:30 INFO - using testdate: 1463623470 19:04:30 INFO - actual date: 1463623470 19:04:30 INFO - starting webserver on 'localhost:57479' 19:04:30 INFO - SUITE-START | Running 2 tests 19:04:30 INFO - TEST-START | dromaeo_css 19:04:30 INFO - operating with platform_type : mac_ 19:04:30 INFO - Initialising browser for dromaeo_css test... 19:04:30 INFO - TEST-INFO | started process 2859 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp2C5Dpp/profile http://localhost:57479/getInfo.html) 19:04:33 INFO - PROCESS | 2859 | __metrics Screen width/height:1600/1200 19:04:33 INFO - PROCESS | 2859 | colorDepth:24 19:04:33 INFO - PROCESS | 2859 | Browser inner width/height: 1024/689 19:04:33 INFO - PROCESS | 2859 | __metrics 19:04:33 ERROR - PROCESS | 2859 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 19:04:34 INFO - TEST-INFO | 2859: exit 0 19:04:34 INFO - Browser initialized. 19:04:34 INFO - Running cycle 1/1 for dromaeo_css test... 19:04:34 INFO - TEST-INFO | started process 2862 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmp2C5Dpp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:46 INFO - PROCESS | 2862 | RSS: Main: 249712640 19:04:46 INFO - PROCESS | 2862 | 19:05:36 INFO - PROCESS | 2862 | 1463623536004 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] 19:07:46 INFO - PROCESS | 2862 | Cycle 1(1): loaded http://localhost:57479/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57479/tests/dromaeo/cssquery-ext.html) 19:07:47 INFO - PROCESS | 2862 | RSS: Main: 263462912 19:07:47 INFO - PROCESS | 2862 | 19:10:50 INFO - PROCESS | 2862 | Cycle 1(1): loaded http://localhost:57479/tests/dromaeo/cssquery-ext.html (next: http://localhost:57479/tests/dromaeo/cssquery-jquery.html) 19:10:50 INFO - PROCESS | 2862 | RSS: Main: 275173376 19:10:50 INFO - PROCESS | 2862 | 19:13:52 INFO - PROCESS | 2862 | Cycle 1(1): loaded http://localhost:57479/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57479/tests/dromaeo/cssquery-mootools.html) 19:13:53 INFO - PROCESS | 2862 | RSS: Main: 277114880 19:13:53 INFO - PROCESS | 2862 | 19:16:55 INFO - PROCESS | 2862 | Cycle 1(1): loaded http://localhost:57479/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57479/tests/dromaeo/cssquery-prototype.html) 19:16:55 INFO - PROCESS | 2862 | RSS: Main: 278679552 19:16:55 INFO - PROCESS | 2862 | 19:19:58 INFO - PROCESS | 2862 | Cycle 1(1): loaded http://localhost:57479/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57479/tests/dromaeo/cssquery-yui.html) 19:19:59 INFO - PROCESS | 2862 | RSS: Main: 281534464 19:19:59 INFO - PROCESS | 2862 | 19:23:01 INFO - PROCESS | 2862 | Cycle 1(1): loaded http://localhost:57479/tests/dromaeo/cssquery-yui.html (next: http://localhost:57479/tests/dromaeo/cssquery-dojo.html) 19:23:02 INFO - PROCESS | 2862 | RSS: Main: 282787840 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | __start_tp_report 19:23:02 INFO - PROCESS | 2862 | _x_x_mozilla_page_load 19:23:02 INFO - PROCESS | 2862 | _x_x_mozilla_page_load_details 19:23:02 INFO - PROCESS | 2862 | |i|pagename|runs| 19:23:02 INFO - PROCESS | 2862 | |0;dojo.html;3467.861285548578;3474.3051389722064;3497.143199916021;3508.631593682663;3512.227309991801;5120.487951204877;5627.677495788492;5863.1205319202145;5898.88202235955;5918.881622367556;5809.941900580993;5858.912116318259;5869.1196320551935;5877.14781356703;5953.940460595392;5237.214417837316;5238.816641417551;5341.679499230058;5383.81156659517;5398.865028374283;78.55010782787531;5236.633435659505;5283.973580132094;5339.012282727708;5340.732963351837;5255.211718242256;5265.578753699714;5275;5304.734763261821;5323.387810401815;4438.4451943507065;5306.602004849652;5327.600429967768;5380.381256155553;5387.461253874581;4521.367008618796;5182;5211.765470553834;5271.130263506517;5340.62615616907;5765.308163020464;5784.508316793078;5796.768129274824;5842.649441033552;5888.470037696585;6127.739336776432;6282.654454005028;6327.873442531123;6366.299707032223;6376.26672932614;6227.564070515065;6252.124702541648;6269;6343.27052388972;6348.143000694893;6089.878202435926;6100.969495152496;6118.816435506942;6126;6130.448259656563;6244.875102497925;6282.717277722513;6414.486841052704;6419.550631455753;6477.838054048687;6205.875882482325;6230.314665386803;6260.686965651699;6271.717772700239;6307.463865571478;6258.311585725658;6287.993920972623;6335.461485773761;6340.809775706736;6344.873102537924;6215.689215539205;6216.285127210338;6262.812115636538;6315.115883776275;6319.494440444753;6265.530085243625;6285.2457705075685;6294.496440284766;6296.275928268216;6310.905336419957;4043.4743483347565;5004;5032;5069.594432445396;5075.746212689351;4481.305397663367;4505.932411013838;4510.751908645056;4563.201439748031;4572.4055872737;5744.167095771173;5858.94141058584;5921.467067963904;5971.5222782177325;5988.670623115683;6323.430891219812;6374.968125159345;6375.936240637627;6381.649009304533;6387.201599800025;5850.385709500526;5928.970355148197;5953.642781433128;5977.880442391128;5998.430149135826;5530.253415788898;5542.224088627595;5549.445055494418;5557.166425036196;5585.748641311151;5059.569936555434;6125.846853828601;6457.1282876811965;6460.192475940507;6463.7737679181;6206.844828879314;6227.159333489921;6281.7173227203975;6305.211848518935;6332.240131184288;6431.5819471734185;6448.9032664511005;6449.96775016122;6458.61248325102;6491.415772580491;5412.891742165056;5516.200150978166;6267.2479302484;6346.048092786119;6349.619022858643;5393.27190829233;6264;6269.153664255267;6294.496440284858;6322.209723784527;5064.772085256099;6223.564350495421;6277.435030847246;6289.7169627365965;6319.684015799284;6116.579253789288;6205.627662340274;6277.780277690259;6282.994720844643;6289.685515724287;4822.348982887405;5140.845774626767;5465.934142842108;6208;6213.565050446426;6201.441870231701;6253.09330147117;6287.371262873676;6295.905561416491;6303.275123360872;6093.2078829751845;6103.4588915260565;6137.907931381123;6218.875622487434;6251.562390632612 19:23:02 INFO - PROCESS | 2862 | |1;ext.html;512.6001718659445;513.8869448721282;521.8904030153666;521.922230593824;541.4206798725363;31171;41557.37663935036;41634.79182604086;41713.374299385534;41731.37402938951;42803.78598107013;43433.34849977245;43543.91140221496;43576.56423435765;43579.346309805376;9487.146156845865;9678.886928003265;9715.174210192143;9734.464604446752;9743.561539730728;9501.619935202583;9568.42589444632;9579.185769209626;9592.90407095927;9597.66408175714;9669.75825604362;9737.805243895118;9741.415515069084;9743.95128024359;9785.119339259465;9638.855417168714;9669.98465161155;9683.467409292487;9704.980976997384;9709.902900971007;9558.28312876537;9603.855942160873;9674.90325096747;9677.37097088687;9686.401743712278;39441.802790985865;39926.80036599827;40015;40024.799876000434;40039.59960400388;2623.27859838544;2874.3820078682975;3114.532820076984;3353.02762198962;3451.879184228565;25416.49167016649;27772.444551108863;29644.258893527618;30438.086857394315;31399.058028259187;2397.5964541749663;2785.902493412741;2798.4822807780624;2885.7402833745064;2895.811772234798;525687;528395;536818;537124;538623;31857.8407107963;36061;52588.73705631447;62383.064254035315;62423.687881560305;32939.341213175605;34230;36264.912052638465;37571;37737.245255095295;15423.151726654918;15493.380264789508;15514.767278490832;15523.611909702122;15536.92231538835;12430.813537796941;12936.870631293756;13025.739485210432;13052.804207936888;13097.279649619171;8226;8261.8347633047;8268.586570671563;8286.627101780434;8308.833823323499;3415.9239839450493;3418.931621367559;3436.814299066818;3451.2752322012657;3455.550778398792;848.6987119572586;943.1087622196958;1001.2040427859869;1110.0509064749683;1131.4116659337099;29568;29943.101706948826;30139;30423.087307380814;30545;1048.308116643012;1118.9649574144048;1126.1385040444068;1177.1348059176498;1189.1854079955085;13693.657658558615;14422.06256593318;15308.770368444482;15353.385864565516;15791.605209869846;654491;685565;752035;768849;942205;62057.37942620606;62068;62410.37589624045;62733;62914.37085629177;1242.7079636285466;1450.7026059657744;1456.2209218068494;1468.0971202710257;1473.0940471609883;29879.188375909027;30258.346760314034;30444.543331849596;30448.543271851384;30561.54157687592;42675;53811.73094134661;53919.46080539301;53920;54018;403904;403943;417719;419757;421928;26906.73093269042;27297.86351068232;27539.449211015268;27744.72255277502;27790.44419111647;45241;45319.773401132785;45848.54151458576;46179.53820461887;46212.53787462082;37578.812105940386;37597.81201093977;37712;38286.42570361391;38419.80790096144;36083.73706920245;37387.252254954205;37510.43734343933;37597.43603845999;37791.244175116895;36699.63300366962;37108.814455927546;37189.62810371862;37268.580895635554;38057.619423806515;32005.519917201727;32444.67555324511;32610;32715.34569308553;32725.836370817993;22332;23303.067877285397;24565.263042108767;24754.87622561876;25022.74977250277 19:23:02 INFO - PROCESS | 2862 | |2;jquery.html;324.5991200866929;362.83672347443655;384.72107721901625;406.3721550204933;418.78432607207293;19438.90280548597;20538.97305134741;20752.792472075274;21387.786122138776;21435.356939291807;17624.471265862045;20441.693374599392;20689.68965465519;20930.89534552325;21131.57736845262;5168.793248270064;5647.152927060943;5884.970575147118;5912.556558258127;5924.1113832924975;5776.364599894008;5864.2962844458725;5869.471747542721;5890.057590785475;5893.174955506232;5498.505134537891;5763.567732420085;5763.769449222026;5790.507806836424;5835.620684655484;5682.715864206773;5758.654480731148;5762.538996880239;5767.192593036978;5768.74040668171;4445.222086134913;4982.526659967333;5122.795088196468;5136.717480538568;5138.97430512845;25344.873275633505;25478.36304092394;25845.483090338086;25901.611475827878;25948.87025564879;2176.1404245323165;2246.70792796937;2246.9662955055687;2327.325075728037;2490.9003639854386;14653.853461465356;14677.77983330251;14753.926230368887;15180.848191518055;15334.693306133926;1665.5586269638557;1798.7751531058618;1803.179553303244;1812.8451320829888;1831.0570056420954;54902;104488.47755761248;104780;106714.46642766736;109952.4502377491;9844.310898237198;11206.38364889939;11312.717182070513;11368.886311136948;11590.536378544764;14184.716305674036;14264.286785660675;14304.28478576067;14352.784708229385;14411.42354305837;8702.912970870337;8778.956105219433;8865.468071915706;8954.955225223832;8980.371374003757;8104.824800403909;8281.461704989157;8855.822883542292;8860.778480537909;8955.596998135099;6872.62200578973;6988.965055174692;7019.157701075904;7024.894626580605;7027.297270272932;1896.6965285554245;1917.3893929099345;1917.5589614388768;1925.8844469331884;1942.3104797796861;902.4404868981143;965.1072757699101;966.4153187321709;1036.036486067949;1087.4182312462794;13718.93140534291;20558.085137221977;21250.36248912535;21389.78610213909;22228.11087556512;938.9014153513783;998.0967224661692;1023.5752162852482;1026.5688410867454;1068.7167900506372;10553.577856885639;10599.89400105989;10999.61501347449;11096.05683516894;11104.500297486633;587929;591148;600489;600594;605022;101032.49483752534;131851.68148318585;136281.9557706635;138598;141345;1050.6060227414719;1058.396713873085;1092.306385445232;1097.237419993097;1104.6741211342512;18088.81911180924;18801.717974230673;18926.905365473085;18986.905065474584;19259.133338999498;45262.547374525835;52051.73974130105;52074.218886717485;53933.46066539284;55214;77209.84185237339;77675.61162194153;78096.50455927024;78221;80216;7964.20357964199;8118.837623247384;8173.182681732017;8177.836443271221;8439.240468357877;11653.59212427561;12144.574939877062;12158.75682486363;12614.180078294881;12625.242485450903;10357.378557286587;10504.00635818788;10626.203034772268;10689.144868410353;10787.244892857738;9169.587368568564;9490.14588686994;10385.688429347132;10717;10812.621558245424;10708;10855.511501982272;10878.78242435131;11091.389973551532;11172.776544469227;6633.402993730394;7062.540934839219;7481.70073197066;7489.325960663567;8499.405041647025;9401.764955876159;10904.236703430684;11174.497147628213;11247.831282530933;11272.380019099028 19:23:02 INFO - PROCESS | 2862 | |3;mootools.html;1591.2680167123124;1609.0747820003496;1814.111085568072;1877.4086162858698;1974.9901250493745;7541.208173141823;8116.269535741782;8672.30621550276;8683.218510334069;9590.184834289084;8800.603972821222;8912.777180570489;9016.36885418021;9027.052159523255;9046.3667543272;2464.2730394533655;2505.235903049569;2511.0709037656093;2512.773850353468;2524.116559204282;2480.8263421560496;2513.2334637935423;2525.5832787590025;2533.4933013397304;2539;2488.0794106180647;2496.138832102931;2508.2224510401834;2508.611165269386;2519.7984161267145;2478.491909158618;2481.8883150258193;2490.9750902490923;2502.224310463762;2502.5745623244093;2471.3080337505526;2485.8135639827087;2490.750924907513;2497.2133777859917;2499.3376755159898;8940.955295223548;8970.013298537157;9022.14289642483;9033.728988130366;9042.276617870555;4961.677490963076;5384.380796208407;5555.2778138842095;5569.081101618228;5583.3858275589655;3530.1527633368064;3591.6587924147166;3601.2617413430185;3644.671979521856;3659.451082337671;1030.304544432505;1032.390889375272;1038.2732087538677;1043.9008294212113;1051.7686109055996;11990.220635658654;12015.21901076436;12070.155089143764;12081.395930203455;12129.454174562254;4772.3557319762085;4886.413630364379;4908.772806798301;5014.84955451337;5017.448080711119;9002.594883230271;10222.386656800616;10240.897591024144;10245.385276883411;10258.487075646188;5503.064479038573;5510.476504732044;5517.227588137664;5523.917141242885;5545.500904918576;4510.030343476168;4876.634252431011;5010.924836127461;5016.398032236155;5026.09530284545;4162.958370416318;4173.353130264813;4177.394277829698;4185.497740271187;4224.134052519287;1366.760816857046;1388.7361401333715;1392.7214557088623;1397.6994946086436;1403.4456389726206;918.136951265808;930.7673081729567;931.3434029009532;946.9337146399686;975.8487434447673;5818;5978.103284507271;6014.218151640259;6135.7852475164045;6160.599561028519;813.515958004986;875.9956200219085;886.7871710789493;940.948247846375;971.8580667715406;3936.4095385692376;4042.3734321179677;4073.3889916512762;4159.126583417456;4170.9582904171175;10766.138708903269;11018.228723989243;11101.666949991513;11129.220954533104;11149.442527873574;10516.053555180071;11079.224454288122;11134.554617815196;11142.220044596801;11198.216124871344;7225.421966242688;7293.562386256842;8003.919960800317;8288.627011784483;8464.06895241523;5028.7176769923435;5860.238169037998;5915.43822766024;5938.465538101592;5944.702764861826;10103.040211179927;10274;10286.691399258034;10288.2798204125;10335.966403359904;9793.85309220348;10281.280310378203;10351.63769268072;10383.688489345332;10486.108680762221;6996.965015174892;7121;7193.928060719326;8092;8355.080941096354;8428.11504791988;8576.142385761374;8585.399022068395;8632.697855575025;8639.95680021596;7564.810879728051;7716.151223365313;7725.652345644572;7763.728269510766;7843.960780196063;7409.147947986051;7532.962335188289;7556.055493063367;7663;7675.270849269311;5792.275965504312;7152.034475345762;7255.165655949633;7274.090738657668;7317.926820731725;4744.217204161275;4944.184209605375;5052.772625231947;5076.746162691777;5077.0861244974785;4984.152694041949;4988.351514303117;5026.246063090565;5093.923591146062;5307.442718514655 19:23:02 INFO - PROCESS | 2862 | |4;prototype.html;322.061191626409;324.2978950572012;328.3482287658997;329.58801498127355;334.52998537055447;5177.611679124062;5426.728663566821;5563.527100196483;5651.717414129287;5710.257666503359;5505.421930697279;5519.806806761764;5529.529989950859;5537.646500237403;5580.525655319303;4010.2180074885337;4078.490188726409;4175.561566035568;4190.874273771792;4224.387463817745;3448.310337932411;3794.354959656865;4179.707420480568;4192.239259511012;4244.745315281078;3701.3152566775098;4084.9991752020796;4186.958130418688;4188.434561334227;4215.009472773896;4032.778197199153;4080.10237747695;4222.0289333453175;4225.809838557241;4232.433999420219;2996.8801247950055;3515.3145136698354;3651.7991510466913;3667.9816600917093;3668.5047518585184;4626;4869.975650121763;5216.739163041832;5307.01820163271;5325.707086110262;4252.829886804555;4390.8902277443;4493.7977790999485;4820.8071677132875;4928.605711543068;5213.348331458567;5262.657927234755;5646.774129034834;5839.328477225131;5899.351071382144;4106.7125301228925;4129.752214867118;4132.772697501636;4147.274227010261;4147.880072380471;5914.37899020601;5953.970230148821;6045.3652366501765;6155.569110162291;6160.02879078695;3554.9822250889097;3942.3495123304333;4032.2540330039037;4189.895252618709;4195;3928.508936382952;3952.1424930283297;4244.405783190356;4265.637420819266;4266.658667306608;3966.286068507696;4391.780410979438;4923.178423983078;4929.309896614477;4942.530459606403;4960.652754307164;4961.727105009187;5233.947660523422;5486.299247233371;5777.0467872801355;1415.688548519324;1424.444466658004;1454.985450145506;1464.6850927050734;1466.4207637982936;791.3708601661692;857.2199298638334;870.991290087091;899.3075331994459;916.9403988740711;4582.625212436255;5285.392179899284;5322.973385133127;5383.650062745909;5395.167537043084;4926.926096108561;5352.759125839347;5536.280283563111;5775.971120144456;5890.087036509348;4699.765011749399;4826.927596086061;5050.747462626854;5374.48942350476;5387.6228663993925;3005.744511716485;3082.3681145365595;3173.254285242966;3183.395154920604;3186.2193762528327;4894.923116914273;5157.561607263349;5660.29246344207;5697.857553561112;6240.438360547572;4827;5146.794128234904;5803.1212245264815;6160;6165.876682466415;5113.846584602468;6206.875862482725;6214.5028397728975;6239.500839932885;6241.781537646069;4999.375078115236;5022.824201152942;5760.971195143998;5976.462118409364;5991.910121348271;5012.1980483122525;5277.182036784305;5880.794172203952;5956.076808094752;6024.819255422344;5357.410684824588;5572.359178694502;5605.187247848958;5759.625624334405;6184.134221209124;3913.834951456275;4130.173965206911;4144.254034273859;4146.626803587692;4158.147579746084;5046.536504413679;5499.890002200013;5828.504577110993;5958.463738263577;6063.818085457443;5118.692878427158;5361.892762144657;5467.343918729619;5801.316164235656;5806.419358064329;5121.8463446096675;5762.971185144047;5765.509931655856;5768.221290125779;5784.7107644618445;4639.744814035261;4929.827456039165;5060.240963855451;5490.752916118864;5690.573207009408;5020.920502091922;5481.820544084501;5658.490735833795;5676.77292908279;5733.9713301434895;5343.728915210942;5792.420757924342;5796.855078623068;5802.82591522255;5832.300123985149 19:23:02 INFO - PROCESS | 2862 | |5;yui.html;665.5973136252567;731.9780406587804;743.6319022084069;748.8315129095953;768.3430666779904;12407;12761.425735841885;15316.387344506235;15552.989055711385;15666.216689165525;11816.409179541031;13422.328883555832;14533.563993080197;14546.636334091652;14547.418103275882;747.7943565519471;915.3180880244195;924.6532550293657;945.6122989574276;958.58301638787;917.8714979902786;935.2331088507426;937.873387092744;939.6241503398627;944.6268723854073;843.7342237195264;995.2436148527099;1003.282652903174;1003.5183112106193;1004.2116938203511;850.9531975741331;987.4272921705393;989.8911119776819;991.167419367731;993.5231089077247;731.0897932074548;739.9899137677032;742.2429122295282;745.697992313114;746.6304179431162;14809.407623695039;14969.476068337664;14991.550253492413;15012.024218425768;15146.394144234217;11011.28426652273;11622.360770157638;12714.491420343174;12839.422225999853;13365.131266467648;9899.158571521357;9910.108090271839;9963.601455941825;10015.098641122335;10056.195504359634;3475.7740746851628;3482.4602186660854;3483.89548313551;3490.3542844573835;3758.6132805668326;76210.85683714748;76596.61701691512;77541.22458775454;78058.60970695167;78775;8637.524936128448;9509.870524296615;9545.570449329796;9569.28230382724;9638.90361096394;22010.559788804134;22048.559028819334;22405.43986400353;22444.66333005006;22452.663210051862;7398.630068496554;7429.591372474566;7439.888401673979;7516.5114267572435;7722.884156737653;3999.9400008999887;4059.9594004060173;4071.959280407217;4072.6741860651077;4087.9386809197886;3526.453399723047;3610.4403817408343;3693.039809649457;3716.2567486502808;3724.981375093107;711.7046425733263;716.5808002318702;717.7416130193126;725.1262229014026;729.1905984357359;491.7737840593367;581.9650820950757;583.1777194156219;599.2629066248538;603.8007457539002;7452.441066920002;8113.797155071171;8285.50286982783;8328.458650187718;8391.118932512121;531.9654222475526;569.5329829539737;593.6764463367475;619.44250174843;625.8341539492038;3941.645251927283;3963.504561929759;3981.880543583697;3983.8027749658104;4014.5182578090817;58721.70639146777;87896.56051719701;90714.09285907188;102710.48644756728;105074.4746276279;76483.47033059307;79294.41411171744;79683.8047429289;82019;82762;75014.24985750181;77023.22976770272;79679;79847;80720.59639701764;8162;8180.877286840583;8298.668053277801;8349.749507514784;8361.95819020901;39143.60856391399;45114.097718044795;46203.30695039644;47248.81877953079;47352;63463.365366345744;63480.41298967378;63807.680961594895;63910.68044659933;63920.72158556896;11094.722631934266;11113.277636953571;11178.198190139703;11256.662300131009;11534.711632209262;11817.231879927776;12136.211146275464;12424.503019879103;12595.496180152692;12876.935615321863;11485.425728713364;11507.884921151017;11905.107116966088;11924.344161071225;11946.283223006647;10904.64401825397;11196.328220306808;11253.63535922554;11291.096712262835;11384.43077846121;9223.44659320443;10846.566137354417;10986;11459;11790.056795456514;9298.72103836886;9497.762555936157;9661.033896610565;9715.611375544899;9893.950530247303;7981.401394895523;9412.905870941477;9639.373440726331;9642.42145471274;9695.321327507007 19:23:02 INFO - PROCESS | 2862 | __end_tp_report 19:23:02 INFO - PROCESS | 2862 | __start_cc_report 19:23:02 INFO - PROCESS | 2862 | _x_x_mozilla_cycle_collect,415 19:23:02 INFO - PROCESS | 2862 | __end_cc_report 19:23:02 INFO - PROCESS | 2862 | __startTimestamp1463624582200__endTimestamp 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | ------- Summary: start ------- 19:23:02 INFO - PROCESS | 2862 | Number of tests: 6 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | [#0] dojo.html Cycles:165 Average:5773.33 Median:6118.82 stddev:808.44 (13.2%) stddev-sans-first:790.42 19:23:02 INFO - PROCESS | 2862 | Values: 3467.9 3474.3 3497.1 3508.6 3512.2 5120.5 5627.7 5863.1 5898.9 5918.9 5809.9 5858.9 5869.1 5877.1 5953.9 5237.2 5238.8 5341.7 5383.8 5398.9 78.6 5236.6 5284.0 5339.0 5340.7 5255.2 5265.6 5275.0 5304.7 5323.4 4438.4 5306.6 5327.6 5380.4 5387.5 4521.4 5182.0 5211.8 5271.1 5340.6 5765.3 5784.5 5796.8 5842.6 5888.5 6127.7 6282.7 6327.9 6366.3 6376.3 6227.6 6252.1 6269.0 6343.3 6348.1 6089.9 6101.0 6118.8 6126.0 6130.4 6244.9 6282.7 6414.5 6419.6 6477.8 6205.9 6230.3 6260.7 6271.7 6307.5 6258.3 6288.0 6335.5 6340.8 6344.9 6215.7 6216.3 6262.8 6315.1 6319.5 6265.5 6285.2 6294.5 6296.3 6310.9 4043.5 5004.0 5032.0 5069.6 5075.7 4481.3 4505.9 4510.8 4563.2 4572.4 5744.2 5858.9 5921.5 5971.5 5988.7 6323.4 6375.0 6375.9 6381.6 6387.2 5850.4 5929.0 5953.6 5977.9 5998.4 5530.3 5542.2 5549.4 5557.2 5585.7 5059.6 6125.8 6457.1 6460.2 6463.8 6206.8 6227.2 6281.7 6305.2 6332.2 6431.6 6448.9 6450.0 6458.6 6491.4 5412.9 5516.2 6267.2 6346.0 6349.6 5393.3 6264.0 6269.2 6294.5 6322.2 5064.8 6223.6 6277.4 6289.7 6319.7 6116.6 6205.6 6277.8 6283.0 6289.7 4822.3 5140.8 5465.9 6208.0 6213.6 6201.4 6253.1 6287.4 6295.9 6303.3 6093.2 6103.5 6137.9 6218.9 6251.6 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | [#1] ext.html Cycles:180 Average:68720.82 Median:37724.62 stddev:158738.30 (420.8%) stddev-sans-first:159100.99 19:23:02 INFO - PROCESS | 2862 | Values: 512.6 513.9 521.9 521.9 541.4 31171.0 41557.4 41634.8 41713.4 41731.4 42803.8 43433.3 43543.9 43576.6 43579.3 9487.1 9678.9 9715.2 9734.5 9743.6 9501.6 9568.4 9579.2 9592.9 9597.7 9669.8 9737.8 9741.4 9744.0 9785.1 9638.9 9670.0 9683.5 9705.0 9709.9 9558.3 9603.9 9674.9 9677.4 9686.4 39441.8 39926.8 40015.0 40024.8 40039.6 2623.3 2874.4 3114.5 3353.0 3451.9 25416.5 27772.4 29644.3 30438.1 31399.1 2397.6 2785.9 2798.5 2885.7 2895.8 525687.0 528395.0 536818.0 537124.0 538623.0 31857.8 36061.0 52588.7 62383.1 62423.7 32939.3 34230.0 36264.9 37571.0 37737.2 15423.2 15493.4 15514.8 15523.6 15536.9 12430.8 12936.9 13025.7 13052.8 13097.3 8226.0 8261.8 8268.6 8286.6 8308.8 3415.9 3418.9 3436.8 3451.3 3455.6 848.7 943.1 1001.2 1110.1 1131.4 29568.0 29943.1 30139.0 30423.1 30545.0 1048.3 1119.0 1126.1 1177.1 1189.2 13693.7 14422.1 15308.8 15353.4 15791.6 654491.0 685565.0 752035.0 768849.0 942205.0 62057.4 62068.0 62410.4 62733.0 62914.4 1242.7 1450.7 1456.2 1468.1 1473.1 29879.2 30258.3 30444.5 30448.5 30561.5 42675.0 53811.7 53919.5 53920.0 54018.0 403904.0 403943.0 417719.0 419757.0 421928.0 26906.7 27297.9 27539.4 27744.7 27790.4 45241.0 45319.8 45848.5 46179.5 46212.5 37578.8 37597.8 37712.0 38286.4 38419.8 36083.7 37387.3 37510.4 37597.4 37791.2 36699.6 37108.8 37189.6 37268.6 38057.6 32005.5 32444.7 32610.0 32715.3 32725.8 22332.0 23303.1 24565.3 24754.9 25022.7 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | [#2] jquery.html Cycles:180 Average:34489.43 Median:12237.41 stddev:99335.31 (811.7%) stddev-sans-first:99580.85 19:23:02 INFO - PROCESS | 2862 | Values: 324.6 362.8 384.7 406.4 418.8 19438.9 20539.0 20752.8 21387.8 21435.4 17624.5 20441.7 20689.7 20930.9 21131.6 5168.8 5647.2 5885.0 5912.6 5924.1 5776.4 5864.3 5869.5 5890.1 5893.2 5498.5 5763.6 5763.8 5790.5 5835.6 5682.7 5758.7 5762.5 5767.2 5768.7 4445.2 4982.5 5122.8 5136.7 5139.0 25344.9 25478.4 25845.5 25901.6 25948.9 2176.1 2246.7 2247.0 2327.3 2490.9 14653.9 14677.8 14753.9 15180.8 15334.7 1665.6 1798.8 1803.2 1812.8 1831.1 54902.0 104488.5 104780.0 106714.5 109952.5 9844.3 11206.4 11312.7 11368.9 11590.5 14184.7 14264.3 14304.3 14352.8 14411.4 8702.9 8779.0 8865.5 8955.0 8980.4 8104.8 8281.5 8855.8 8860.8 8955.6 6872.6 6989.0 7019.2 7024.9 7027.3 1896.7 1917.4 1917.6 1925.9 1942.3 902.4 965.1 966.4 1036.0 1087.4 13718.9 20558.1 21250.4 21389.8 22228.1 938.9 998.1 1023.6 1026.6 1068.7 10553.6 10599.9 10999.6 11096.1 11104.5 587929.0 591148.0 600489.0 600594.0 605022.0 101032.5 131851.7 136282.0 138598.0 141345.0 1050.6 1058.4 1092.3 1097.2 1104.7 18088.8 18801.7 18926.9 18986.9 19259.1 45262.5 52051.7 52074.2 53933.5 55214.0 77209.8 77675.6 78096.5 78221.0 80216.0 7964.2 8118.8 8173.2 8177.8 8439.2 11653.6 12144.6 12158.8 12614.2 12625.2 10357.4 10504.0 10626.2 10689.1 10787.2 9169.6 9490.1 10385.7 10717.0 10812.6 10708.0 10855.5 10878.8 11091.4 11172.8 6633.4 7062.5 7481.7 7489.3 8499.4 9401.8 10904.2 11174.5 11247.8 11272.4 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | [#3] mootools.html Cycles:180 Average:5841.57 Median:4972.92 stddev:3248.41 (65.3%) stddev-sans-first:3241.82 19:23:02 INFO - PROCESS | 2862 | Values: 1591.3 1609.1 1814.1 1877.4 1975.0 7541.2 8116.3 8672.3 8683.2 9590.2 8800.6 8912.8 9016.4 9027.1 9046.4 2464.3 2505.2 2511.1 2512.8 2524.1 2480.8 2513.2 2525.6 2533.5 2539.0 2488.1 2496.1 2508.2 2508.6 2519.8 2478.5 2481.9 2491.0 2502.2 2502.6 2471.3 2485.8 2490.8 2497.2 2499.3 8941.0 8970.0 9022.1 9033.7 9042.3 4961.7 5384.4 5555.3 5569.1 5583.4 3530.2 3591.7 3601.3 3644.7 3659.5 1030.3 1032.4 1038.3 1043.9 1051.8 11990.2 12015.2 12070.2 12081.4 12129.5 4772.4 4886.4 4908.8 5014.8 5017.4 9002.6 10222.4 10240.9 10245.4 10258.5 5503.1 5510.5 5517.2 5523.9 5545.5 4510.0 4876.6 5010.9 5016.4 5026.1 4163.0 4173.4 4177.4 4185.5 4224.1 1366.8 1388.7 1392.7 1397.7 1403.4 918.1 930.8 931.3 946.9 975.8 5818.0 5978.1 6014.2 6135.8 6160.6 813.5 876.0 886.8 940.9 971.9 3936.4 4042.4 4073.4 4159.1 4171.0 10766.1 11018.2 11101.7 11129.2 11149.4 10516.1 11079.2 11134.6 11142.2 11198.2 7225.4 7293.6 8003.9 8288.6 8464.1 5028.7 5860.2 5915.4 5938.5 5944.7 10103.0 10274.0 10286.7 10288.3 10336.0 9793.9 10281.3 10351.6 10383.7 10486.1 6997.0 7121.0 7193.9 8092.0 8355.1 8428.1 8576.1 8585.4 8632.7 8640.0 7564.8 7716.2 7725.7 7763.7 7844.0 7409.1 7533.0 7556.1 7663.0 7675.3 5792.3 7152.0 7255.2 7274.1 7317.9 4744.2 4944.2 5052.8 5076.7 5077.1 4984.2 4988.4 5026.2 5093.9 5307.4 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | [#4] prototype.html Cycles:180 Average:4644.00 Median:5120.27 stddev:1395.34 (27.3%) stddev-sans-first:1361.03 19:23:02 INFO - PROCESS | 2862 | Values: 322.1 324.3 328.3 329.6 334.5 5177.6 5426.7 5563.5 5651.7 5710.3 5505.4 5519.8 5529.5 5537.6 5580.5 4010.2 4078.5 4175.6 4190.9 4224.4 3448.3 3794.4 4179.7 4192.2 4244.7 3701.3 4085.0 4187.0 4188.4 4215.0 4032.8 4080.1 4222.0 4225.8 4232.4 2996.9 3515.3 3651.8 3668.0 3668.5 4626.0 4870.0 5216.7 5307.0 5325.7 4252.8 4390.9 4493.8 4820.8 4928.6 5213.3 5262.7 5646.8 5839.3 5899.4 4106.7 4129.8 4132.8 4147.3 4147.9 5914.4 5954.0 6045.4 6155.6 6160.0 3555.0 3942.3 4032.3 4189.9 4195.0 3928.5 3952.1 4244.4 4265.6 4266.7 3966.3 4391.8 4923.2 4929.3 4942.5 4960.7 4961.7 5233.9 5486.3 5777.0 1415.7 1424.4 1455.0 1464.7 1466.4 791.4 857.2 871.0 899.3 916.9 4582.6 5285.4 5323.0 5383.7 5395.2 4926.9 5352.8 5536.3 5776.0 5890.1 4699.8 4826.9 5050.7 5374.5 5387.6 3005.7 3082.4 3173.3 3183.4 3186.2 4894.9 5157.6 5660.3 5697.9 6240.4 4827.0 5146.8 5803.1 6160.0 6165.9 5113.8 6206.9 6214.5 6239.5 6241.8 4999.4 5022.8 5761.0 5976.5 5991.9 5012.2 5277.2 5880.8 5956.1 6024.8 5357.4 5572.4 5605.2 5759.6 6184.1 3913.8 4130.2 4144.3 4146.6 4158.1 5046.5 5499.9 5828.5 5958.5 6063.8 5118.7 5361.9 5467.3 5801.3 5806.4 5121.8 5763.0 5765.5 5768.2 5784.7 4639.7 4929.8 5060.2 5490.8 5690.6 5020.9 5481.8 5658.5 5676.8 5734.0 5343.7 5792.4 5796.9 5802.8 5832.3 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - PROCESS | 2862 | [#5] yui.html Cycles:180 Average:18146.49 Median:622.64 stddev:25655.54 (4120.5%) stddev-sans-first:25693.93 19:23:02 INFO - PROCESS | 2862 | Values: 665.6 732.0 743.6 748.8 768.3 12407.0 12761.4 15316.4 15553.0 15666.2 11816.4 13422.3 14533.6 14546.6 14547.4 747.8 915.3 924.7 945.6 958.6 917.9 935.2 937.9 939.6 944.6 843.7 995.2 1003.3 1003.5 1004.2 851.0 987.4 989.9 991.2 993.5 731.1 740.0 742.2 745.7 746.6 14809.4 14969.5 14991.6 15012.0 15146.4 11011.3 11622.4 12714.5 12839.4 13365.1 9899.2 9910.1 9963.6 10015.1 10056.2 3475.8 3482.5 3483.9 3490.4 3758.6 76210.9 76596.6 77541.2 78058.6 78775.0 8637.5 9509.9 9545.6 9569.3 9638.9 22010.6 22048.6 22405.4 22444.7 22452.7 7398.6 7429.6 7439.9 7516.5 7722.9 3999.9 4060.0 4072.0 4072.7 4087.9 3526.5 3610.4 3693.0 3716.3 3725.0 711.7 716.6 717.7 725.1 729.2 491.8 582.0 583.2 599.3 603.8 7452.4 8113.8 8285.5 8328.5 8391.1 532.0 569.5 593.7 619.4 625.8 3941.6 3963.5 3981.9 3983.8 4014.5 58721.7 87896.6 90714.1 102710.5 105074.5 76483.5 79294.4 79683.8 82019.0 82762.0 75014.2 77023.2 79679.0 79847.0 80720.6 8162.0 8180.9 8298.7 8349.7 8362.0 39143.6 45114.1 46203.3 47248.8 47352.0 63463.4 63480.4 63807.7 63910.7 63920.7 11094.7 11113.3 11178.2 11256.7 11534.7 11817.2 12136.2 12424.5 12595.5 12876.9 11485.4 11507.9 11905.1 11924.3 11946.3 10904.6 11196.3 11253.6 11291.1 11384.4 9223.4 10846.6 10986.0 11459.0 11790.1 9298.7 9497.8 9661.0 9715.6 9894.0 7981.4 9412.9 9639.4 9642.4 9695.3 19:23:02 INFO - PROCESS | 2862 | -------- Summary: end -------- 19:23:02 INFO - PROCESS | 2862 | 19:23:02 INFO - TEST-INFO | 2862: exit 0 19:23:02 INFO - TEST-OK | dromaeo_css | took 1112288ms 19:23:02 INFO - TEST-START | kraken 19:23:02 INFO - operating with platform_type : mac_ 19:23:02 INFO - Initialising browser for kraken test... 19:23:02 INFO - TEST-INFO | started process 2866 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpHW1c7R/profile http://localhost:57479/getInfo.html) 19:23:05 INFO - PROCESS | 2866 | __metrics Screen width/height:1600/1200 19:23:05 INFO - PROCESS | 2866 | colorDepth:24 19:23:05 INFO - PROCESS | 2866 | Browser inner width/height: 1024/689 19:23:05 INFO - PROCESS | 2866 | __metrics 19:23:05 ERROR - PROCESS | 2866 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 19:23:06 INFO - TEST-INFO | 2866: exit 0 19:23:06 INFO - Browser initialized. 19:23:06 INFO - Running cycle 1/1 for kraken test... 19:23:06 INFO - TEST-INFO | started process 2869 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpHW1c7R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:18 INFO - PROCESS | 2869 | RSS: Main: 249499648 19:23:18 INFO - PROCESS | 2869 | 19:23:21 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57479/tests/kraken/driver.html?testName=audio-beat-detection) 19:23:22 INFO - PROCESS | 2869 | RSS: Main: 261328896 19:23:22 INFO - PROCESS | 2869 | 19:23:25 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57479/tests/kraken/driver.html?testName=audio-dft) 19:23:25 INFO - PROCESS | 2869 | RSS: Main: 266063872 19:23:25 INFO - PROCESS | 2869 | 19:23:29 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57479/tests/kraken/driver.html?testName=audio-fft) 19:23:30 INFO - PROCESS | 2869 | RSS: Main: 266485760 19:23:30 INFO - PROCESS | 2869 | 19:23:32 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57479/tests/kraken/driver.html?testName=audio-oscillator) 19:23:32 INFO - PROCESS | 2869 | RSS: Main: 267051008 19:23:32 INFO - PROCESS | 2869 | 19:23:34 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57479/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:23:35 INFO - PROCESS | 2869 | RSS: Main: 267661312 19:23:35 INFO - PROCESS | 2869 | 19:23:38 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57479/tests/kraken/driver.html?testName=imaging-darkroom) 19:23:39 INFO - PROCESS | 2869 | RSS: Main: 268140544 19:23:39 INFO - PROCESS | 2869 | 19:23:42 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57479/tests/kraken/driver.html?testName=imaging-desaturate) 19:23:43 INFO - PROCESS | 2869 | RSS: Main: 268472320 19:23:43 INFO - PROCESS | 2869 | 19:23:46 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57479/tests/kraken/driver.html?testName=json-parse-financial) 19:23:46 INFO - PROCESS | 2869 | RSS: Main: 268509184 19:23:46 INFO - PROCESS | 2869 | 19:23:48 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57479/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:23:49 INFO - PROCESS | 2869 | RSS: Main: 267862016 19:23:49 INFO - PROCESS | 2869 | 19:23:51 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:23:51 INFO - PROCESS | 2869 | RSS: Main: 267841536 19:23:51 INFO - PROCESS | 2869 | 19:23:54 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:23:54 INFO - PROCESS | 2869 | RSS: Main: 267948032 19:23:54 INFO - PROCESS | 2869 | 19:23:57 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:23:58 INFO - PROCESS | 2869 | RSS: Main: 268169216 19:23:58 INFO - PROCESS | 2869 | 19:24:01 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:24:01 INFO - PROCESS | 2869 | RSS: Main: 268009472 19:24:01 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | Cycle 1(1): loaded http://localhost:57479/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57479/tests/kraken/driver.html?testName=ai-astar) 19:24:03 INFO - PROCESS | 2869 | RSS: Main: 269905920 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | __start_tp_report 19:24:03 INFO - PROCESS | 2869 | _x_x_mozilla_page_load 19:24:03 INFO - PROCESS | 2869 | _x_x_mozilla_page_load_details 19:24:03 INFO - PROCESS | 2869 | |i|pagename|runs| 19:24:03 INFO - PROCESS | 2869 | |0;ai-astar;114;118;111;114;113;114;108;134;151;115 19:24:03 INFO - PROCESS | 2869 | |1;audio-beat-detection;159;144;173;240;107;165;158;146;198;140 19:24:03 INFO - PROCESS | 2869 | |2;audio-dft;194;208;249;270;247;198;195;198;200;196 19:24:03 INFO - PROCESS | 2869 | |3;audio-fft;74;82;77;178;65;79;80;81;135;58 19:24:03 INFO - PROCESS | 2869 | |4;audio-oscillator;81;84;151;80;83;85;124;78;81;124 19:24:03 INFO - PROCESS | 2869 | |5;imaging-gaussian-blur;89;96;88;129;132;117;90;88;90;85 19:24:03 INFO - PROCESS | 2869 | |6;imaging-darkroom;94;95;95;100;126;147;131;99;95;96 19:24:03 INFO - PROCESS | 2869 | |7;imaging-desaturate;131;146;92;92;91;91;92;90;91;92 19:24:03 INFO - PROCESS | 2869 | |8;json-parse-financial;56;58;58;57;57;62;65;160;85;83 19:24:03 INFO - PROCESS | 2869 | |9;json-stringify-tinderbox;64;69;65;78;74;71;66;66;86;56 19:24:03 INFO - PROCESS | 2869 | |10;stanford-crypto-aes;86;87;87;84;86;88;85;87;85;87 19:24:03 INFO - PROCESS | 2869 | |11;stanford-crypto-ccm;142;135;138;136;136;138;149;145;212;140 19:24:03 INFO - PROCESS | 2869 | |12;stanford-crypto-pbkdf2;173;163;162;161;167;208;193;162;145;206 19:24:03 INFO - PROCESS | 2869 | |13;stanford-crypto-sha256-iterative;67;64;68;69;69;64;67;66;80;86 19:24:03 INFO - PROCESS | 2869 | __end_tp_report 19:24:03 INFO - PROCESS | 2869 | __start_cc_report 19:24:03 INFO - PROCESS | 2869 | _x_x_mozilla_cycle_collect,1080 19:24:03 INFO - PROCESS | 2869 | __end_cc_report 19:24:03 INFO - PROCESS | 2869 | __startTimestamp1463624643910__endTimestamp 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | ------- Summary: start ------- 19:24:03 INFO - PROCESS | 2869 | Number of tests: 14 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#0] ai-astar Cycles:10 Average:119.20 Median:114.50 stddev:13.17 (11.5%) stddev-sans-first:13.84 19:24:03 INFO - PROCESS | 2869 | Values: 114.0 118.0 111.0 114.0 113.0 114.0 108.0 134.0 151.0 115.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#1] audio-beat-detection Cycles:10 Average:163.00 Median:162.00 stddev:35.92 (22.2%) stddev-sans-first:38.07 19:24:03 INFO - PROCESS | 2869 | Values: 159.0 144.0 173.0 240.0 107.0 165.0 158.0 146.0 198.0 140.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#2] audio-dft Cycles:10 Average:215.50 Median:204.00 stddev:28.40 (13.9%) stddev-sans-first:29.03 19:24:03 INFO - PROCESS | 2869 | Values: 194.0 208.0 249.0 270.0 247.0 198.0 195.0 198.0 200.0 196.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#3] audio-fft Cycles:10 Average:90.90 Median:78.00 stddev:36.82 (47.2%) stddev-sans-first:38.54 19:24:03 INFO - PROCESS | 2869 | Values: 74.0 82.0 77.0 178.0 65.0 79.0 80.0 81.0 135.0 58.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:81.00 stddev:25.92 (32.0%) stddev-sans-first:26.83 19:24:03 INFO - PROCESS | 2869 | Values: 81.0 84.0 151.0 80.0 83.0 85.0 124.0 78.0 81.0 124.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:88.50 stddev:18.26 (20.6%) stddev-sans-first:18.90 19:24:03 INFO - PROCESS | 2869 | Values: 89.0 96.0 88.0 129.0 132.0 117.0 90.0 88.0 90.0 85.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:95.00 stddev:19.34 (20.4%) stddev-sans-first:19.86 19:24:03 INFO - PROCESS | 2869 | Values: 94.0 95.0 95.0 100.0 126.0 147.0 131.0 99.0 95.0 96.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#7] imaging-desaturate Cycles:10 Average:100.80 Median:91.50 stddev:20.19 (22.1%) stddev-sans-first:18.22 19:24:03 INFO - PROCESS | 2869 | Values: 131.0 146.0 92.0 92.0 91.0 91.0 92.0 90.0 91.0 92.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#8] json-parse-financial Cycles:10 Average:74.10 Median:60.00 stddev:32.04 (53.4%) stddev-sans-first:33.30 19:24:03 INFO - PROCESS | 2869 | Values: 56.0 58.0 58.0 57.0 57.0 62.0 65.0 160.0 85.0 83.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:8.33 (11.9%) stddev-sans-first:8.59 19:24:03 INFO - PROCESS | 2869 | Values: 64.0 69.0 65.0 78.0 74.0 71.0 66.0 66.0 86.0 56.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:87.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 19:24:03 INFO - PROCESS | 2869 | Values: 86.0 87.0 87.0 84.0 86.0 88.0 85.0 87.0 85.0 87.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:141.00 stddev:23.23 (16.5%) stddev-sans-first:24.56 19:24:03 INFO - PROCESS | 2869 | Values: 142.0 135.0 138.0 136.0 136.0 138.0 149.0 145.0 212.0 140.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:170.00 stddev:21.11 (12.4%) stddev-sans-first:22.39 19:24:03 INFO - PROCESS | 2869 | Values: 173.0 163.0 162.0 161.0 167.0 208.0 193.0 162.0 145.0 206.0 19:24:03 INFO - PROCESS | 2869 | 19:24:03 INFO - PROCESS | 2869 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:68.50 stddev:7.21 (10.5%) stddev-sans-first:7.57 19:24:03 INFO - PROCESS | 2869 | Values: 67.0 64.0 68.0 69.0 69.0 64.0 67.0 66.0 80.0 86.0 19:24:03 INFO - PROCESS | 2869 | -------- Summary: end -------- 19:24:03 INFO - PROCESS | 2869 | 19:24:04 INFO - TEST-INFO | 2869: exit 0 19:24:04 INFO - TEST-OK | kraken | took 61888ms 19:24:04 INFO - SUITE-END | took 1174s 19:24:05 INFO - Completed test suite (00:19:34) 19:24:05 INFO - javascript benchmark 19:24:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7212.676164788705, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3467.861285548578, 3474.3051389722064, 3497.143199916021, 3508.631593682663, 3512.227309991801, 5120.487951204877, 5627.677495788492, 5863.1205319202145, 5898.88202235955, 5918.881622367556, 5809.941900580993, 5858.912116318259, 5869.1196320551935, 5877.14781356703, 5953.940460595392, 5237.214417837316, 5238.816641417551, 5341.679499230058, 5383.81156659517, 5398.865028374283, 78.55010782787531, 5236.633435659505, 5283.973580132094, 5339.012282727708, 5340.732963351837, 5255.211718242256, 5265.578753699714, 5275.0, 5304.734763261821, 5323.387810401815, 4438.4451943507065, 5306.602004849652, 5327.600429967768, 5380.381256155553, 5387.461253874581, 4521.367008618796, 5182.0, 5211.765470553834, 5271.130263506517, 5340.62615616907, 5765.308163020464, 5784.508316793078, 5796.768129274824, 5842.649441033552, 5888.470037696585, 6127.739336776432, 6282.654454005028, 6327.873442531123, 6366.299707032223, 6376.26672932614, 6227.564070515065, 6252.124702541648, 6269.0, 6343.27052388972, 6348.143000694893, 6089.878202435926, 6100.969495152496, 6118.816435506942, 6126.0, 6130.448259656563, 6244.875102497925, 6282.717277722513, 6414.486841052704, 6419.550631455753, 6477.838054048687, 6205.875882482325, 6230.314665386803, 6260.686965651699, 6271.717772700239, 6307.463865571478, 6258.311585725658, 6287.993920972623, 6335.461485773761, 6340.809775706736, 6344.873102537924, 6215.689215539205, 6216.285127210338, 6262.812115636538, 6315.115883776275, 6319.494440444753, 6265.530085243625, 6285.2457705075685, 6294.496440284766, 6296.275928268216, 6310.905336419957, 4043.4743483347565, 5004.0, 5032.0, 5069.594432445396, 5075.746212689351, 4481.305397663367, 4505.932411013838, 4510.751908645056, 4563.201439748031, 4572.4055872737, 5744.167095771173, 5858.94141058584, 5921.467067963904, 5971.5222782177325, 5988.670623115683, 6323.430891219812, 6374.968125159345, 6375.936240637627, 6381.649009304533, 6387.201599800025, 5850.385709500526, 5928.970355148197, 5953.642781433128, 5977.880442391128, 5998.430149135826, 5530.253415788898, 5542.224088627595, 5549.445055494418, 5557.166425036196, 5585.748641311151, 5059.569936555434, 6125.846853828601, 6457.1282876811965, 6460.192475940507, 6463.7737679181, 6206.844828879314, 6227.159333489921, 6281.7173227203975, 6305.211848518935, 6332.240131184288, 6431.5819471734185, 6448.9032664511005, 6449.96775016122, 6458.61248325102, 6491.415772580491, 5412.891742165056, 5516.200150978166, 6267.2479302484, 6346.048092786119, 6349.619022858643, 5393.27190829233, 6264.0, 6269.153664255267, 6294.496440284858, 6322.209723784527, 5064.772085256099, 6223.564350495421, 6277.435030847246, 6289.7169627365965, 6319.684015799284, 6116.579253789288, 6205.627662340274, 6277.780277690259, 6282.994720844643, 6289.685515724287, 4822.348982887405, 5140.845774626767, 5465.934142842108, 6208.0, 6213.565050446426, 6201.441870231701, 6253.09330147117, 6287.371262873676, 6295.905561416491, 6303.275123360872, 6093.2078829751845, 6103.4588915260565, 6137.907931381123, 6218.875622487434, 6251.562390632612], "value": 5726.022187168334, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6001718659445, 513.8869448721282, 521.8904030153666, 521.922230593824, 541.4206798725363, 31171.0, 41557.37663935036, 41634.79182604086, 41713.374299385534, 41731.37402938951, 42803.78598107013, 43433.34849977245, 43543.91140221496, 43576.56423435765, 43579.346309805376, 9487.146156845865, 9678.886928003265, 9715.174210192143, 9734.464604446752, 9743.561539730728, 9501.619935202583, 9568.42589444632, 9579.185769209626, 9592.90407095927, 9597.66408175714, 9669.75825604362, 9737.805243895118, 9741.415515069084, 9743.95128024359, 9785.119339259465, 9638.855417168714, 9669.98465161155, 9683.467409292487, 9704.980976997384, 9709.902900971007, 9558.28312876537, 9603.855942160873, 9674.90325096747, 9677.37097088687, 9686.401743712278, 39441.802790985865, 39926.80036599827, 40015.0, 40024.799876000434, 40039.59960400388, 2623.27859838544, 2874.3820078682975, 3114.532820076984, 3353.02762198962, 3451.879184228565, 25416.49167016649, 27772.444551108863, 29644.258893527618, 30438.086857394315, 31399.058028259187, 2397.5964541749663, 2785.902493412741, 2798.4822807780624, 2885.7402833745064, 2895.811772234798, 525687.0, 528395.0, 536818.0, 537124.0, 538623.0, 31857.8407107963, 36061.0, 52588.73705631447, 62383.064254035315, 62423.687881560305, 32939.341213175605, 34230.0, 36264.912052638465, 37571.0, 37737.245255095295, 15423.151726654918, 15493.380264789508, 15514.767278490832, 15523.611909702122, 15536.92231538835, 12430.813537796941, 12936.870631293756, 13025.739485210432, 13052.804207936888, 13097.279649619171, 8226.0, 8261.8347633047, 8268.586570671563, 8286.627101780434, 8308.833823323499, 3415.9239839450493, 3418.931621367559, 3436.814299066818, 3451.2752322012657, 3455.550778398792, 848.6987119572586, 943.1087622196958, 1001.2040427859869, 1110.0509064749683, 1131.4116659337099, 29568.0, 29943.101706948826, 30139.0, 30423.087307380814, 30545.0, 1048.308116643012, 1118.9649574144048, 1126.1385040444068, 1177.1348059176498, 1189.1854079955085, 13693.657658558615, 14422.06256593318, 15308.770368444482, 15353.385864565516, 15791.605209869846, 654491.0, 685565.0, 752035.0, 768849.0, 942205.0, 62057.37942620606, 62068.0, 62410.37589624045, 62733.0, 62914.37085629177, 1242.7079636285466, 1450.7026059657744, 1456.2209218068494, 1468.0971202710257, 1473.0940471609883, 29879.188375909027, 30258.346760314034, 30444.543331849596, 30448.543271851384, 30561.54157687592, 42675.0, 53811.73094134661, 53919.46080539301, 53920.0, 54018.0, 403904.0, 403943.0, 417719.0, 419757.0, 421928.0, 26906.73093269042, 27297.86351068232, 27539.449211015268, 27744.72255277502, 27790.44419111647, 45241.0, 45319.773401132785, 45848.54151458576, 46179.53820461887, 46212.53787462082, 37578.812105940386, 37597.81201093977, 37712.0, 38286.42570361391, 38419.80790096144, 36083.73706920245, 37387.252254954205, 37510.43734343933, 37597.43603845999, 37791.244175116895, 36699.63300366962, 37108.814455927546, 37189.62810371862, 37268.580895635554, 38057.619423806515, 32005.519917201727, 32444.67555324511, 32610.0, 32715.34569308553, 32725.836370817993, 22332.0, 23303.067877285397, 24565.263042108767, 24754.87622561876, 25022.74977250277], "value": 18547.062262384825, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.5991200866929, 362.83672347443655, 384.72107721901625, 406.3721550204933, 418.78432607207293, 19438.90280548597, 20538.97305134741, 20752.792472075274, 21387.786122138776, 21435.356939291807, 17624.471265862045, 20441.693374599392, 20689.68965465519, 20930.89534552325, 21131.57736845262, 5168.793248270064, 5647.152927060943, 5884.970575147118, 5912.556558258127, 5924.1113832924975, 5776.364599894008, 5864.2962844458725, 5869.471747542721, 5890.057590785475, 5893.174955506232, 5498.505134537891, 5763.567732420085, 5763.769449222026, 5790.507806836424, 5835.620684655484, 5682.715864206773, 5758.654480731148, 5762.538996880239, 5767.192593036978, 5768.74040668171, 4445.222086134913, 4982.526659967333, 5122.795088196468, 5136.717480538568, 5138.97430512845, 25344.873275633505, 25478.36304092394, 25845.483090338086, 25901.611475827878, 25948.87025564879, 2176.1404245323165, 2246.70792796937, 2246.9662955055687, 2327.325075728037, 2490.9003639854386, 14653.853461465356, 14677.77983330251, 14753.926230368887, 15180.848191518055, 15334.693306133926, 1665.5586269638557, 1798.7751531058618, 1803.179553303244, 1812.8451320829888, 1831.0570056420954, 54902.0, 104488.47755761248, 104780.0, 106714.46642766736, 109952.4502377491, 9844.310898237198, 11206.38364889939, 11312.717182070513, 11368.886311136948, 11590.536378544764, 14184.716305674036, 14264.286785660675, 14304.28478576067, 14352.784708229385, 14411.42354305837, 8702.912970870337, 8778.956105219433, 8865.468071915706, 8954.955225223832, 8980.371374003757, 8104.824800403909, 8281.461704989157, 8855.822883542292, 8860.778480537909, 8955.596998135099, 6872.62200578973, 6988.965055174692, 7019.157701075904, 7024.894626580605, 7027.297270272932, 1896.6965285554245, 1917.3893929099345, 1917.5589614388768, 1925.8844469331884, 1942.3104797796861, 902.4404868981143, 965.1072757699101, 966.4153187321709, 1036.036486067949, 1087.4182312462794, 13718.93140534291, 20558.085137221977, 21250.36248912535, 21389.78610213909, 22228.11087556512, 938.9014153513783, 998.0967224661692, 1023.5752162852482, 1026.5688410867454, 1068.7167900506372, 10553.577856885639, 10599.89400105989, 10999.61501347449, 11096.05683516894, 11104.500297486633, 587929.0, 591148.0, 600489.0, 600594.0, 605022.0, 101032.49483752534, 131851.68148318585, 136281.9557706635, 138598.0, 141345.0, 1050.6060227414719, 1058.396713873085, 1092.306385445232, 1097.237419993097, 1104.6741211342512, 18088.81911180924, 18801.717974230673, 18926.905365473085, 18986.905065474584, 19259.133338999498, 45262.547374525835, 52051.73974130105, 52074.218886717485, 53933.46066539284, 55214.0, 77209.84185237339, 77675.61162194153, 78096.50455927024, 78221.0, 80216.0, 7964.20357964199, 8118.837623247384, 8173.182681732017, 8177.836443271221, 8439.240468357877, 11653.59212427561, 12144.574939877062, 12158.75682486363, 12614.180078294881, 12625.242485450903, 10357.378557286587, 10504.00635818788, 10626.203034772268, 10689.144868410353, 10787.244892857738, 9169.587368568564, 9490.14588686994, 10385.688429347132, 10717.0, 10812.621558245424, 10708.0, 10855.511501982272, 10878.78242435131, 11091.389973551532, 11172.776544469227, 6633.402993730394, 7062.540934839219, 7481.70073197066, 7489.325960663567, 8499.405041647025, 9401.764955876159, 10904.236703430684, 11174.497147628213, 11247.831282530933, 11272.380019099028], "value": 9649.391365162417, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1591.2680167123124, 1609.0747820003496, 1814.111085568072, 1877.4086162858698, 1974.9901250493745, 7541.208173141823, 8116.269535741782, 8672.30621550276, 8683.218510334069, 9590.184834289084, 8800.603972821222, 8912.777180570489, 9016.36885418021, 9027.052159523255, 9046.3667543272, 2464.2730394533655, 2505.235903049569, 2511.0709037656093, 2512.773850353468, 2524.116559204282, 2480.8263421560496, 2513.2334637935423, 2525.5832787590025, 2533.4933013397304, 2539.0, 2488.0794106180647, 2496.138832102931, 2508.2224510401834, 2508.611165269386, 2519.7984161267145, 2478.491909158618, 2481.8883150258193, 2490.9750902490923, 2502.224310463762, 2502.5745623244093, 2471.3080337505526, 2485.8135639827087, 2490.750924907513, 2497.2133777859917, 2499.3376755159898, 8940.955295223548, 8970.013298537157, 9022.14289642483, 9033.728988130366, 9042.276617870555, 4961.677490963076, 5384.380796208407, 5555.2778138842095, 5569.081101618228, 5583.3858275589655, 3530.1527633368064, 3591.6587924147166, 3601.2617413430185, 3644.671979521856, 3659.451082337671, 1030.304544432505, 1032.390889375272, 1038.2732087538677, 1043.9008294212113, 1051.7686109055996, 11990.220635658654, 12015.21901076436, 12070.155089143764, 12081.395930203455, 12129.454174562254, 4772.3557319762085, 4886.413630364379, 4908.772806798301, 5014.84955451337, 5017.448080711119, 9002.594883230271, 10222.386656800616, 10240.897591024144, 10245.385276883411, 10258.487075646188, 5503.064479038573, 5510.476504732044, 5517.227588137664, 5523.917141242885, 5545.500904918576, 4510.030343476168, 4876.634252431011, 5010.924836127461, 5016.398032236155, 5026.09530284545, 4162.958370416318, 4173.353130264813, 4177.394277829698, 4185.497740271187, 4224.134052519287, 1366.760816857046, 1388.7361401333715, 1392.7214557088623, 1397.6994946086436, 1403.4456389726206, 918.136951265808, 930.7673081729567, 931.3434029009532, 946.9337146399686, 975.8487434447673, 5818.0, 5978.103284507271, 6014.218151640259, 6135.7852475164045, 6160.599561028519, 813.515958004986, 875.9956200219085, 886.7871710789493, 940.948247846375, 971.8580667715406, 3936.4095385692376, 4042.3734321179677, 4073.3889916512762, 4159.126583417456, 4170.9582904171175, 10766.138708903269, 11018.228723989243, 11101.666949991513, 11129.220954533104, 11149.442527873574, 10516.053555180071, 11079.224454288122, 11134.554617815196, 11142.220044596801, 11198.216124871344, 7225.421966242688, 7293.562386256842, 8003.919960800317, 8288.627011784483, 8464.06895241523, 5028.7176769923435, 5860.238169037998, 5915.43822766024, 5938.465538101592, 5944.702764861826, 10103.040211179927, 10274.0, 10286.691399258034, 10288.2798204125, 10335.966403359904, 9793.85309220348, 10281.280310378203, 10351.63769268072, 10383.688489345332, 10486.108680762221, 6996.965015174892, 7121.0, 7193.928060719326, 8092.0, 8355.080941096354, 8428.11504791988, 8576.142385761374, 8585.399022068395, 8632.697855575025, 8639.95680021596, 7564.810879728051, 7716.151223365313, 7725.652345644572, 7763.728269510766, 7843.960780196063, 7409.147947986051, 7532.962335188289, 7556.055493063367, 7663.0, 7675.270849269311, 5792.275965504312, 7152.034475345762, 7255.165655949633, 7274.090738657668, 7317.926820731725, 4744.217204161275, 4944.184209605375, 5052.772625231947, 5076.746162691777, 5077.0861244974785, 4984.152694041949, 4988.351514303117, 5026.246063090565, 5093.923591146062, 5307.442718514655], "value": 4717.65925946202, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.061191626409, 324.2978950572012, 328.3482287658997, 329.58801498127355, 334.52998537055447, 5177.611679124062, 5426.728663566821, 5563.527100196483, 5651.717414129287, 5710.257666503359, 5505.421930697279, 5519.806806761764, 5529.529989950859, 5537.646500237403, 5580.525655319303, 4010.2180074885337, 4078.490188726409, 4175.561566035568, 4190.874273771792, 4224.387463817745, 3448.310337932411, 3794.354959656865, 4179.707420480568, 4192.239259511012, 4244.745315281078, 3701.3152566775098, 4084.9991752020796, 4186.958130418688, 4188.434561334227, 4215.009472773896, 4032.778197199153, 4080.10237747695, 4222.0289333453175, 4225.809838557241, 4232.433999420219, 2996.8801247950055, 3515.3145136698354, 3651.7991510466913, 3667.9816600917093, 3668.5047518585184, 4626.0, 4869.975650121763, 5216.739163041832, 5307.01820163271, 5325.707086110262, 4252.829886804555, 4390.8902277443, 4493.7977790999485, 4820.8071677132875, 4928.605711543068, 5213.348331458567, 5262.657927234755, 5646.774129034834, 5839.328477225131, 5899.351071382144, 4106.7125301228925, 4129.752214867118, 4132.772697501636, 4147.274227010261, 4147.880072380471, 5914.37899020601, 5953.970230148821, 6045.3652366501765, 6155.569110162291, 6160.02879078695, 3554.9822250889097, 3942.3495123304333, 4032.2540330039037, 4189.895252618709, 4195.0, 3928.508936382952, 3952.1424930283297, 4244.405783190356, 4265.637420819266, 4266.658667306608, 3966.286068507696, 4391.780410979438, 4923.178423983078, 4929.309896614477, 4942.530459606403, 4960.652754307164, 4961.727105009187, 5233.947660523422, 5486.299247233371, 5777.0467872801355, 1415.688548519324, 1424.444466658004, 1454.985450145506, 1464.6850927050734, 1466.4207637982936, 791.3708601661692, 857.2199298638334, 870.991290087091, 899.3075331994459, 916.9403988740711, 4582.625212436255, 5285.392179899284, 5322.973385133127, 5383.650062745909, 5395.167537043084, 4926.926096108561, 5352.759125839347, 5536.280283563111, 5775.971120144456, 5890.087036509348, 4699.765011749399, 4826.927596086061, 5050.747462626854, 5374.48942350476, 5387.6228663993925, 3005.744511716485, 3082.3681145365595, 3173.254285242966, 3183.395154920604, 3186.2193762528327, 4894.923116914273, 5157.561607263349, 5660.29246344207, 5697.857553561112, 6240.438360547572, 4827.0, 5146.794128234904, 5803.1212245264815, 6160.0, 6165.876682466415, 5113.846584602468, 6206.875862482725, 6214.5028397728975, 6239.500839932885, 6241.781537646069, 4999.375078115236, 5022.824201152942, 5760.971195143998, 5976.462118409364, 5991.910121348271, 5012.1980483122525, 5277.182036784305, 5880.794172203952, 5956.076808094752, 6024.819255422344, 5357.410684824588, 5572.359178694502, 5605.187247848958, 5759.625624334405, 6184.134221209124, 3913.834951456275, 4130.173965206911, 4144.254034273859, 4146.626803587692, 4158.147579746084, 5046.536504413679, 5499.890002200013, 5828.504577110993, 5958.463738263577, 6063.818085457443, 5118.692878427158, 5361.892762144657, 5467.343918729619, 5801.316164235656, 5806.419358064329, 5121.8463446096675, 5762.971185144047, 5765.509931655856, 5768.221290125779, 5784.7107644618445, 4639.744814035261, 4929.827456039165, 5060.240963855451, 5490.752916118864, 5690.573207009408, 5020.920502091922, 5481.820544084501, 5658.490735833795, 5676.77292908279, 5733.9713301434895, 5343.728915210942, 5792.420757924342, 5796.855078623068, 5802.82591522255, 5832.300123985149], "value": 4203.318947229751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.5973136252567, 731.9780406587804, 743.6319022084069, 748.8315129095953, 768.3430666779904, 12407.0, 12761.425735841885, 15316.387344506235, 15552.989055711385, 15666.216689165525, 11816.409179541031, 13422.328883555832, 14533.563993080197, 14546.636334091652, 14547.418103275882, 747.7943565519471, 915.3180880244195, 924.6532550293657, 945.6122989574276, 958.58301638787, 917.8714979902786, 935.2331088507426, 937.873387092744, 939.6241503398627, 944.6268723854073, 843.7342237195264, 995.2436148527099, 1003.282652903174, 1003.5183112106193, 1004.2116938203511, 850.9531975741331, 987.4272921705393, 989.8911119776819, 991.167419367731, 993.5231089077247, 731.0897932074548, 739.9899137677032, 742.2429122295282, 745.697992313114, 746.6304179431162, 14809.407623695039, 14969.476068337664, 14991.550253492413, 15012.024218425768, 15146.394144234217, 11011.28426652273, 11622.360770157638, 12714.491420343174, 12839.422225999853, 13365.131266467648, 9899.158571521357, 9910.108090271839, 9963.601455941825, 10015.098641122335, 10056.195504359634, 3475.7740746851628, 3482.4602186660854, 3483.89548313551, 3490.3542844573835, 3758.6132805668326, 76210.85683714748, 76596.61701691512, 77541.22458775454, 78058.60970695167, 78775.0, 8637.524936128448, 9509.870524296615, 9545.570449329796, 9569.28230382724, 9638.90361096394, 22010.559788804134, 22048.559028819334, 22405.43986400353, 22444.66333005006, 22452.663210051862, 7398.630068496554, 7429.591372474566, 7439.888401673979, 7516.5114267572435, 7722.884156737653, 3999.9400008999887, 4059.9594004060173, 4071.959280407217, 4072.6741860651077, 4087.9386809197886, 3526.453399723047, 3610.4403817408343, 3693.039809649457, 3716.2567486502808, 3724.981375093107, 711.7046425733263, 716.5808002318702, 717.7416130193126, 725.1262229014026, 729.1905984357359, 491.7737840593367, 581.9650820950757, 583.1777194156219, 599.2629066248538, 603.8007457539002, 7452.441066920002, 8113.797155071171, 8285.50286982783, 8328.458650187718, 8391.118932512121, 531.9654222475526, 569.5329829539737, 593.6764463367475, 619.44250174843, 625.8341539492038, 3941.645251927283, 3963.504561929759, 3981.880543583697, 3983.8027749658104, 4014.5182578090817, 58721.70639146777, 87896.56051719701, 90714.09285907188, 102710.48644756728, 105074.4746276279, 76483.47033059307, 79294.41411171744, 79683.8047429289, 82019.0, 82762.0, 75014.24985750181, 77023.22976770272, 79679.0, 79847.0, 80720.59639701764, 8162.0, 8180.877286840583, 8298.668053277801, 8349.749507514784, 8361.95819020901, 39143.60856391399, 45114.097718044795, 46203.30695039644, 47248.81877953079, 47352.0, 63463.365366345744, 63480.41298967378, 63807.680961594895, 63910.68044659933, 63920.72158556896, 11094.722631934266, 11113.277636953571, 11178.198190139703, 11256.662300131009, 11534.711632209262, 11817.231879927776, 12136.211146275464, 12424.503019879103, 12595.496180152692, 12876.935615321863, 11485.425728713364, 11507.884921151017, 11905.107116966088, 11924.344161071225, 11946.283223006647, 10904.64401825397, 11196.328220306808, 11253.63535922554, 11291.096712262835, 11384.43077846121, 9223.44659320443, 10846.566137354417, 10986.0, 11459.0, 11790.056795456514, 9298.72103836886, 9497.762555936157, 9661.033896610565, 9715.611375544899, 9893.950530247303, 7981.401394895523, 9412.905870941477, 9639.373440726331, 9642.42145471274, 9695.321327507007], "value": 6927.723705390112, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1615.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 111.0, 114.0, 113.0, 114.0, 108.0, 134.0, 151.0, 115.0], "value": 119.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 144.0, 173.0, 240.0, 107.0, 165.0, 158.0, 146.0, 198.0, 140.0], "value": 163.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 208.0, 249.0, 270.0, 247.0, 198.0, 195.0, 198.0, 200.0, 196.0], "value": 215.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 77.0, 178.0, 65.0, 79.0, 80.0, 81.0, 135.0, 58.0], "value": 90.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 151.0, 80.0, 83.0, 85.0, 124.0, 78.0, 81.0, 124.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 88.0, 129.0, 132.0, 117.0, 90.0, 88.0, 90.0, 85.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 100.0, 126.0, 147.0, 131.0, 99.0, 95.0, 96.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 146.0, 92.0, 92.0, 91.0, 91.0, 92.0, 90.0, 91.0, 92.0], "value": 100.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 57.0, 57.0, 62.0, 65.0, 160.0, 85.0, 83.0], "value": 74.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 65.0, 78.0, 74.0, 71.0, 66.0, 66.0, 86.0, 56.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 84.0, 86.0, 88.0, 85.0, 87.0, 85.0, 87.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 135.0, 138.0, 136.0, 136.0, 138.0, 149.0, 145.0, 212.0, 140.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 163.0, 162.0, 161.0, 167.0, 208.0, 193.0, 162.0, 145.0, 206.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 68.0, 69.0, 69.0, 64.0, 67.0, 66.0, 80.0, 86.0], "value": 70.0, "unit": "score"}]}]} 19:24:05 INFO - Return code: 0 19:24:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:24:05 ERROR - # TBPL SUCCESS # 19:24:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:24:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:24:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:24:05 INFO - 2016-05-18 19:24:05.226 screenresolution[2874:35965] starting screenresolution argv=screenresolution get 19:24:05 INFO - 2016-05-18 19:24:05.229 screenresolution[2874:35965] Display 0: 1600x1200x32@60 19:24:05 INFO - 2016-05-18 19:24:05.235 screenresolution[2875:35968] starting screenresolution argv=screenresolution list 19:24:05 INFO - Available Modes on Display 0 19:24:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:24:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:24:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:24:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:24:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:24:05 INFO - Intel Iris: 19:24:05 INFO - Chipset Model: Intel Iris 19:24:05 INFO - Type: GPU 19:24:05 INFO - Bus: Built-In 19:24:05 INFO - VRAM (Dynamic, Max): 1536 MB 19:24:05 INFO - Vendor: Intel (0x8086) 19:24:05 INFO - Device ID: 0x0a2e 19:24:05 INFO - Revision ID: 0x0009 19:24:05 INFO - Displays: 19:24:05 INFO - Display: 19:24:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:24:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:24:05 INFO - Display Serial Number: 1600x1200 60 19:24:05 INFO - Main Display: Yes 19:24:05 INFO - Mirror: Off 19:24:05 INFO - Online: Yes 19:24:05 INFO - Rotation: Supported 19:24:05 INFO - Return code: 0 19:24:05 INFO - Running post-action listener: _resource_record_post_action 19:24:05 INFO - ##### 19:24:05 INFO - ##### Finished run-tests step (success) 19:24:05 INFO - ##### 19:24:05 INFO - Running post-run listener: _resource_record_post_run 19:24:05 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 94208; Write bytes: 698704896; Read time: 1; Write time: 1957 19:24:05 INFO - TinderboxPrint: CPU usage
54.9% 19:24:05 INFO - TinderboxPrint: I/O read bytes / time
94,208 / 1 19:24:05 INFO - TinderboxPrint: I/O write bytes / time
698,704,896 / 1,957 19:24:05 INFO - TinderboxPrint: CPU idle
2,182.1 (45.1%) 19:24:05 INFO - TinderboxPrint: CPU system
984.3 (20.3%) 19:24:05 INFO - TinderboxPrint: CPU user
1,675.3 (34.6%) 19:24:05 INFO - TinderboxPrint: Swap in / out
394,956,800 / 0 19:24:05 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 389241344; Write bytes: 383383040; Read time: 29812; Write time: 780 19:24:05 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 94208; Write bytes: 298407424; Read time: 1; Write time: 1123 19:24:05 INFO - Running post-run listener: _upload_blobber_files 19:24:05 INFO - Blob upload gear active. 19:24:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:24:05 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 19:24:05 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'] 19:24:05 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 19:24:06 INFO - (blobuploader) - INFO - Open directory for files ... 19:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:24:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:08 INFO - (blobuploader) - INFO - Done attempting. 19:24:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:24:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:24:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:09 INFO - (blobuploader) - INFO - Done attempting. 19:24:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:24:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:24:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:11 INFO - (blobuploader) - INFO - Done attempting. 19:24:11 INFO - (blobuploader) - INFO - Iteration through files over. 19:24:11 INFO - Return code: 0 19:24:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:24:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:24:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d17100d1996d9d15a7dda3e1e41223d9531708ad5587f4bc5d601ca961634cdcd191120290888cb17c3abd7ddbb35a4197adbf3b2d6acaee0f2b6ddff98b6e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a553d048e617c7301a568e350cfdd12fddaa2ae8d9baa958c73c86ff69aaaffd896d7a59ae92f43eb850afffde6956fb5b524079b817457914fd4b7af28c140", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/993a30523ca12dd4736de60d850e77254b73be70cbc0b214fa613396dbfbad1ecfa9a2bb91d923bea01898e5625c6460cc9c4942db9c72627c55a24bfac74c99"} 19:24:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:24:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:24:11 INFO - Contents: 19:24:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d17100d1996d9d15a7dda3e1e41223d9531708ad5587f4bc5d601ca961634cdcd191120290888cb17c3abd7ddbb35a4197adbf3b2d6acaee0f2b6ddff98b6e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a553d048e617c7301a568e350cfdd12fddaa2ae8d9baa958c73c86ff69aaaffd896d7a59ae92f43eb850afffde6956fb5b524079b817457914fd4b7af28c140", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/993a30523ca12dd4736de60d850e77254b73be70cbc0b214fa613396dbfbad1ecfa9a2bb91d923bea01898e5625c6460cc9c4942db9c72627c55a24bfac74c99"} 19:24:11 INFO - Running post-run listener: copy_logs_to_upload_dir 19:24:11 INFO - Copying logs to upload dir... 19:24:11 INFO - mkdir: /builds/slave/test/build/upload/logs 19:24:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1330.695348 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-05-18 19:24:11.289787) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 19:24:11.293571) ========= 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.9MIeQmMBe6/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.xWCI71XdWj/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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/d17100d1996d9d15a7dda3e1e41223d9531708ad5587f4bc5d601ca961634cdcd191120290888cb17c3abd7ddbb35a4197adbf3b2d6acaee0f2b6ddff98b6e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a553d048e617c7301a568e350cfdd12fddaa2ae8d9baa958c73c86ff69aaaffd896d7a59ae92f43eb850afffde6956fb5b524079b817457914fd4b7af28c140", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/993a30523ca12dd4736de60d850e77254b73be70cbc0b214fa613396dbfbad1ecfa9a2bb91d923bea01898e5625c6460cc9c4942db9c72627c55a24bfac74c99"} build_url:https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010465 build_url: 'https://queue.taskcluster.net/v1/task/NKvGGHNxQDWMKpwTjFnS_g/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/d17100d1996d9d15a7dda3e1e41223d9531708ad5587f4bc5d601ca961634cdcd191120290888cb17c3abd7ddbb35a4197adbf3b2d6acaee0f2b6ddff98b6e64", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a553d048e617c7301a568e350cfdd12fddaa2ae8d9baa958c73c86ff69aaaffd896d7a59ae92f43eb850afffde6956fb5b524079b817457914fd4b7af28c140", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/993a30523ca12dd4736de60d850e77254b73be70cbc0b214fa613396dbfbad1ecfa9a2bb91d923bea01898e5625c6460cc9c4942db9c72627c55a24bfac74c99"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 19:24:11.321661) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:24:11.321990) ========= 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.9MIeQmMBe6/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.xWCI71XdWj/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 19:24:11.371296) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 19:24:11.371605) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 19:24:11.371931) ========= ========= Total master_lag: 0.17 =========