builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0203 starttime: 1464695173.35 results: success (0) buildid: 20160531030039 builduid: 4a496c6c258142099a44b48356697f5e revision: 864cdd00360cdf62ea5132a457ee53a17f9e31aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.352172) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.352626) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.352963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.377282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.377589) ========= 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.TlCjxspFgs/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.Tfln5NEncm/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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-31 04:46:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-05-31 04:46:13 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.512614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.911181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.911516) ========= 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.TlCjxspFgs/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.Tfln5NEncm/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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.026347 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:13.961587) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 04:46:13.961996) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 864cdd00360cdf62ea5132a457ee53a17f9e31aa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 864cdd00360cdf62ea5132a457ee53a17f9e31aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TlCjxspFgs/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.Tfln5NEncm/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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-31 04:46:14,040 truncating revision to first 12 chars 2016-05-31 04:46:14,040 Setting DEBUG logging. 2016-05-31 04:46:14,040 attempt 1/10 2016-05-31 04:46:14,040 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/864cdd00360c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 04:46:15,147 unpacking tar archive at: mozilla-central-864cdd00360c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.379534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 04:46:15.361087) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:15.361395) ========= script_repo_revision: 864cdd00360cdf62ea5132a457ee53a17f9e31aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:15.361821) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:15.362122) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 04:46:15.378664) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-05-31 04:46:15.378998) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TlCjxspFgs/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.Tfln5NEncm/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:46:15 INFO - MultiFileLogger online at 20160531 04:46:15 in /builds/slave/test 04:46:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:46:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:15 INFO - {'append_to_log': False, 04:46:15 INFO - 'base_work_dir': '/builds/slave/test', 04:46:15 INFO - 'blob_upload_branch': 'Firefox', 04:46:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:15 INFO - 'branch': 'Firefox', 04:46:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:15 INFO - 'config_files': ('talos/mac_config.py',), 04:46:15 INFO - 'default_actions': ('clobber', 04:46:15 INFO - 'read-buildbot-config', 04:46:15 INFO - 'download-and-extract', 04:46:15 INFO - 'populate-webroot', 04:46:15 INFO - 'create-virtualenv', 04:46:15 INFO - 'install', 04:46:15 INFO - 'run-tests'), 04:46:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:15 INFO - 'download_minidump_stackwalk': True, 04:46:15 INFO - 'download_symbols': 'ondemand', 04:46:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:15 INFO - 'installer_path': 'installer.exe', 04:46:15 INFO - 'log_level': 'info', 04:46:15 INFO - 'log_name': 'talos', 04:46:15 INFO - 'log_to_console': True, 04:46:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:15 INFO - 'opt_config_files': (), 04:46:15 INFO - 'pip_index': False, 04:46:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:15 INFO - 'cmd': ('bash', 04:46:15 INFO - '-c', 04:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:15 INFO - 'enabled': True, 04:46:15 INFO - 'halt_on_failure': False, 04:46:15 INFO - 'name': 'check_screen_resolution'},), 04:46:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:15 INFO - 'cmd': ('bash', 04:46:15 INFO - '-c', 04:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:15 INFO - 'enabled': True, 04:46:15 INFO - 'halt_on_failure': False, 04:46:15 INFO - 'name': 'check_screen_resolution'},), 04:46:15 INFO - 'run_cmd_checks_enabled': True, 04:46:15 INFO - 'sps_profile': False, 04:46:15 INFO - 'sps_profile_interval': 0, 04:46:15 INFO - 'suite': 'dromaeojs-e10s', 04:46:15 INFO - 'system_bits': '32', 04:46:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:15 INFO - 'title': 't-yosemite-r7-0203', 04:46:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:15 INFO - 'use_talos_json': True, 04:46:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:15 INFO - 'work_dir': 'build'} 04:46:15 INFO - ##### 04:46:15 INFO - ##### Running clobber step. 04:46:15 INFO - ##### 04:46:15 INFO - Running pre-action listener: _resource_record_pre_action 04:46:15 INFO - Running main action method: clobber 04:46:15 INFO - rmtree: /builds/slave/test/build 04:46:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:17 INFO - Running post-action listener: _resource_record_post_action 04:46:17 INFO - ##### 04:46:17 INFO - ##### Finished clobber step (success) 04:46:17 INFO - ##### 04:46:17 INFO - ##### 04:46:17 INFO - ##### Running read-buildbot-config step. 04:46:17 INFO - ##### 04:46:17 INFO - Running pre-action listener: _resource_record_pre_action 04:46:17 INFO - Running main action method: read_buildbot_config 04:46:17 INFO - Using buildbot properties: 04:46:17 INFO - { 04:46:17 INFO - "project": "", 04:46:17 INFO - "product": "firefox", 04:46:17 INFO - "script_repo_revision": "production", 04:46:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:46:17 INFO - "repository": "", 04:46:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:46:17 INFO - "buildid": "20160531030039", 04:46:17 INFO - "pgo_build": "False", 04:46:17 INFO - "basedir": "/builds/slave/test", 04:46:17 INFO - "buildnumber": 238, 04:46:17 INFO - "slavename": "t-yosemite-r7-0203", 04:46:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:46:17 INFO - "platform": "yosemite_r7", 04:46:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:46:17 INFO - "branch": "mozilla-central", 04:46:17 INFO - "repo_path": "mozilla-central", 04:46:17 INFO - "revision": "864cdd00360cdf62ea5132a457ee53a17f9e31aa", 04:46:17 INFO - "stage_platform": "macosx64", 04:46:17 INFO - "builduid": "4a496c6c258142099a44b48356697f5e", 04:46:17 INFO - "slavebuilddir": "test" 04:46:17 INFO - } 04:46:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:46:17 INFO - Running post-action listener: _resource_record_post_action 04:46:17 INFO - ##### 04:46:17 INFO - ##### Finished read-buildbot-config step (success) 04:46:17 INFO - ##### 04:46:17 INFO - ##### 04:46:17 INFO - ##### Running download-and-extract step. 04:46:17 INFO - ##### 04:46:17 INFO - Running pre-action listener: _resource_record_pre_action 04:46:17 INFO - Running main action method: download_and_extract 04:46:17 INFO - mkdir: /builds/slave/test/build/tests 04:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:46:17 INFO - https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:46:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:46:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:46:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:46:47 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json: timed out 04:46:47 INFO - retry: attempt #1 caught exception: timed out 04:46:47 INFO - retry: Failed, sleeping 30 seconds before retrying 04:47:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/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 04:47:21 INFO - Downloaded 1199 bytes. 04:47:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:47:21 INFO - Using the following test package requirements: 04:47:21 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:47:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:47:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:47:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'jsshell-mac.zip'], 04:47:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:47:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:47:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:47:21 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:47:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:47:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:47:21 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:47:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:47:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:21 INFO - https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:47:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:47:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:47:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:47:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:24 INFO - Downloaded 18687106 bytes. 04:47:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:47:27 INFO - Return code: 0 04:47:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:27 INFO - https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:47:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:47:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:47:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:28 INFO - Downloaded 18687106 bytes. 04:47:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:47:31 INFO - Return code: 0 04:47: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')]} 04:47:31 INFO - https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:47:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:47:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:47:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:47:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:47:32 INFO - Downloaded 10834055 bytes. 04:47:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:47:33 INFO - Return code: 0 04:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:33 INFO - https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:47:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:47:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:47:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:47:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:47:38 INFO - Downloaded 111007902 bytes. 04:47:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:47:38 INFO - mkdir: /builds/slave/test/properties 04:47:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:38 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:38 INFO - Contents: 04:47:38 INFO - build_url:https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:47:39 INFO - Running post-action listener: _resource_record_post_action 04:47:39 INFO - Running post-action listener: set_extra_try_arguments 04:47:39 INFO - ##### 04:47:39 INFO - ##### Finished download-and-extract step (success) 04:47:39 INFO - ##### 04:47:39 INFO - ##### 04:47:39 INFO - ##### Running populate-webroot step. 04:47:39 INFO - ##### 04:47:39 INFO - Running pre-action listener: _resource_record_pre_action 04:47:39 INFO - Running main action method: populate_webroot 04:47:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:39 INFO - '--tppagecycles', 04:47:39 INFO - '7'], 04:47:39 INFO - 'tests': ['tsvgm']}}, 04:47:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['damp', 'tps']}, 04:47:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'tests': ['damp', 'tps']}, 04:47:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['dromaeo_dom']}, 04:47:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['a11yr', 04:47:39 INFO - 'ts_paint', 04:47:39 INFO - 'tpaint', 04:47:39 INFO - 'sessionrestore', 04:47:39 INFO - 'sessionrestore_no_auto_restore', 04:47:39 INFO - 'tabpaint']}, 04:47:39 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:39 INFO - 'ts_paint', 04:47:39 INFO - 'tpaint', 04:47:39 INFO - 'sessionrestore', 04:47:39 INFO - 'sessionrestore_no_auto_restore', 04:47:39 INFO - 'tabpaint']}, 04:47:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:39 INFO - 'ts_paint', 04:47:39 INFO - 'tpaint', 04:47:39 INFO - 'sessionrestore', 04:47:39 INFO - 'sessionrestore_no_auto_restore', 04:47:39 INFO - 'tabpaint']}, 04:47:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:39 INFO - 'ts_paint', 04:47:39 INFO - 'tpaint', 04:47:39 INFO - 'sessionrestore', 04:47:39 INFO - 'sessionrestore_no_auto_restore', 04:47:39 INFO - 'tabpaint']}, 04:47:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['a11yr', 04:47:39 INFO - 'ts_paint', 04:47:39 INFO - 'tpaint', 04:47:39 INFO - 'sessionrestore', 04:47:39 INFO - 'sessionrestore_no_auto_restore', 04:47:39 INFO - 'tabpaint']}, 04:47:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['a11yr', 04:47:39 INFO - 'ts_paint', 04:47:39 INFO - 'tpaint', 04:47:39 INFO - 'sessionrestore', 04:47:39 INFO - 'sessionrestore_no_auto_restore', 04:47:39 INFO - 'tabpaint']}, 04:47:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['tsvgx', 04:47:39 INFO - 'tsvgr_opacity', 04:47:39 INFO - 'tart', 04:47:39 INFO - 'tscrollx', 04:47:39 INFO - 'cart']}, 04:47:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:39 INFO - 'tsvgr_opacity', 04:47:39 INFO - 'tart', 04:47:39 INFO - 'tscrollx', 04:47:39 INFO - 'cart']}, 04:47:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'talos_options': ['--disable-e10s'], 04:47:39 INFO - 'tests': ['tp5o']}, 04:47:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'tests': ['tp5o']}, 04:47:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'talos_options': ['--disable-e10s', 04:47:39 INFO - '--xperf_path', 04:47:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:39 INFO - 'tests': ['tp5n']}, 04:47:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:39 INFO - 'talos_options': ['--xperf_path', 04:47:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:39 INFO - 'tests': ['tp5n']}}, 04:47:39 INFO - 'talos.zip': {'path': '', 04:47:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:39 INFO - Running post-action listener: _resource_record_post_action 04:47:39 INFO - ##### 04:47:39 INFO - ##### Finished populate-webroot step (success) 04:47:39 INFO - ##### 04:47:39 INFO - ##### 04:47:39 INFO - ##### Running create-virtualenv step. 04:47:39 INFO - ##### 04:47:39 INFO - Running pre-action listener: _resource_record_pre_action 04:47:39 INFO - Running main action method: create_virtualenv 04:47:39 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:47:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:39 INFO - Using real prefix '/tools/python27' 04:47:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:40 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:43 INFO - Installing pip.................done. 04:47:43 INFO - Return code: 0 04:47:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:43 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:43 INFO - 'HOME': '/Users/cltbld', 04:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:43 INFO - 'LOGNAME': 'cltbld', 04:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:43 INFO - 'MOZ_NO_REMOTE': '1', 04:47:43 INFO - 'NO_EM_RESTART': '1', 04:47:43 INFO - 'PAGER': '/bin/cat', 04:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:43 INFO - 'PWD': '/builds/slave/test', 04:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:43 INFO - 'SHELL': '/bin/bash', 04:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:47:43 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:43 INFO - 'USER': 'cltbld', 04:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:43 INFO - 'XPC_FLAGS': '0x0', 04:47:43 INFO - 'XPC_SERVICE_NAME': '0', 04:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:47:43 INFO - Cleaning up... 04:47:43 INFO - Return code: 0 04:47:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:43 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:43 INFO - 'HOME': '/Users/cltbld', 04:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:43 INFO - 'LOGNAME': 'cltbld', 04:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:43 INFO - 'MOZ_NO_REMOTE': '1', 04:47:43 INFO - 'NO_EM_RESTART': '1', 04:47:43 INFO - 'PAGER': '/bin/cat', 04:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:43 INFO - 'PWD': '/builds/slave/test', 04:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:43 INFO - 'SHELL': '/bin/bash', 04:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:47:43 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:43 INFO - 'USER': 'cltbld', 04:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:43 INFO - 'XPC_FLAGS': '0x0', 04:47:43 INFO - 'XPC_SERVICE_NAME': '0', 04:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:43 INFO - Downloading/unpacking psutil>=3.1.1 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:46 INFO - Installing collected packages: psutil 04:47:46 INFO - Running setup.py install for psutil 04:47:46 INFO - building 'psutil._psutil_osx' extension 04:47:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:47:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:47:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:47:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:47:47 INFO - building 'psutil._psutil_posix' extension 04:47:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:47:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:47 INFO - ^ 04:47:47 INFO - 1 warning generated. 04:47:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:47:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:47 INFO - Successfully installed psutil 04:47:47 INFO - Cleaning up... 04:47:47 INFO - Return code: 0 04:47:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:47 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:47 INFO - 'HOME': '/Users/cltbld', 04:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:47 INFO - 'LOGNAME': 'cltbld', 04:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:47 INFO - 'MOZ_NO_REMOTE': '1', 04:47:47 INFO - 'NO_EM_RESTART': '1', 04:47:47 INFO - 'PAGER': '/bin/cat', 04:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:47 INFO - 'PWD': '/builds/slave/test', 04:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:47 INFO - 'SHELL': '/bin/bash', 04:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:47:47 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:47 INFO - 'USER': 'cltbld', 04:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:47 INFO - 'XPC_FLAGS': '0x0', 04:47:47 INFO - 'XPC_SERVICE_NAME': '0', 04:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:50 INFO - Installing collected packages: mozsystemmonitor 04:47:50 INFO - Running setup.py install for mozsystemmonitor 04:47:50 INFO - Successfully installed mozsystemmonitor 04:47:50 INFO - Cleaning up... 04:47:50 INFO - Return code: 0 04:47:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', '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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47: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 blobuploader==1.2.4 04:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:50 INFO - 'HOME': '/Users/cltbld', 04:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:50 INFO - 'LOGNAME': 'cltbld', 04:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:50 INFO - 'MOZ_NO_REMOTE': '1', 04:47:50 INFO - 'NO_EM_RESTART': '1', 04:47:50 INFO - 'PAGER': '/bin/cat', 04:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:50 INFO - 'PWD': '/builds/slave/test', 04:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:50 INFO - 'SHELL': '/bin/bash', 04:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:47:50 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:50 INFO - 'USER': 'cltbld', 04:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:50 INFO - 'XPC_FLAGS': '0x0', 04:47:50 INFO - 'XPC_SERVICE_NAME': '0', 04:47:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:51 INFO - Downloading/unpacking blobuploader==1.2.4 04:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:53 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:54 INFO - Downloading docopt-0.6.1.tar.gz 04:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:54 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:54 INFO - Running setup.py install for blobuploader 04:47:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:54 INFO - Running setup.py install for requests 04:47:55 INFO - Running setup.py install for docopt 04:47:55 INFO - Successfully installed blobuploader requests docopt 04:47:55 INFO - Cleaning up... 04:47:55 INFO - Return code: 0 04:47:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:55 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:55 INFO - 'HOME': '/Users/cltbld', 04:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:55 INFO - 'LOGNAME': 'cltbld', 04:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:55 INFO - 'MOZ_NO_REMOTE': '1', 04:47:55 INFO - 'NO_EM_RESTART': '1', 04:47:55 INFO - 'PAGER': '/bin/cat', 04:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:55 INFO - 'PWD': '/builds/slave/test', 04:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:55 INFO - 'SHELL': '/bin/bash', 04:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:47:55 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:55 INFO - 'USER': 'cltbld', 04:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:55 INFO - 'XPC_FLAGS': '0x0', 04:47:55 INFO - 'XPC_SERVICE_NAME': '0', 04:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:55 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-lQvmr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-YvAzdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-10RA2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-orUtnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-mxmmUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-OdhnO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-b3cWg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-fRML5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-75rRoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-0RG6Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:56 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-zhx6Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:57 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-_UGjNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:57 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-mZicxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:57 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-t5VnOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:57 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-8vDF51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:57 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-AjBdRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:57 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-ZbHrHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:57 INFO - Running setup.py install for manifestparser 04:47:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:57 INFO - Running setup.py install for mozcrash 04:47:58 INFO - Running setup.py install for mozdebug 04:47:58 INFO - Running setup.py install for mozdevice 04:47:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Running setup.py install for mozfile 04:47:58 INFO - Running setup.py install for mozhttpd 04:47:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Running setup.py install for mozinfo 04:47:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Running setup.py install for mozInstall 04:47:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:58 INFO - Running setup.py install for mozleak 04:47:59 INFO - Running setup.py install for mozlog 04:47:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:59 INFO - Running setup.py install for moznetwork 04:47:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:59 INFO - Running setup.py install for mozprocess 04:47:59 INFO - Running setup.py install for mozprofile 04:47:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:59 INFO - Running setup.py install for mozrunner 04:47:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:59 INFO - Running setup.py install for mozscreenshot 04:47:59 INFO - Running setup.py install for moztest 04:48:00 INFO - Running setup.py install for mozversion 04:48:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:00 INFO - Cleaning up... 04:48:00 INFO - Return code: 0 04:48:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:00 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:48:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:48:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:00 INFO - 'HOME': '/Users/cltbld', 04:48:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:00 INFO - 'LOGNAME': 'cltbld', 04:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:00 INFO - 'MOZ_NO_REMOTE': '1', 04:48:00 INFO - 'NO_EM_RESTART': '1', 04:48:00 INFO - 'PAGER': '/bin/cat', 04:48:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:00 INFO - 'PWD': '/builds/slave/test', 04:48:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:00 INFO - 'SHELL': '/bin/bash', 04:48:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:48:00 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:48:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:00 INFO - 'USER': 'cltbld', 04:48:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:00 INFO - 'XPC_FLAGS': '0x0', 04:48:00 INFO - 'XPC_SERVICE_NAME': '0', 04:48:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:00 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-Ks2VFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:00 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-zOyYoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:00 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-TDX_DW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:00 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-lJ3Ymg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-IkEHUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-BuWLHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-4xxux_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-JKMPu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-iHKlTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-_ziido-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-WPET2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-1eQhG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-UZBMmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:01 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-JBe6w_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:02 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-YEiLkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:02 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-ozkAtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:02 INFO - Running setup.py (path:/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/pip-5hJ0JO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:04 INFO - Downloading blessings-1.6.tar.gz 04:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:04 INFO - Installing collected packages: blessings 04:48:04 INFO - Running setup.py install for blessings 04:48:04 INFO - Successfully installed blessings 04:48:04 INFO - Cleaning up... 04:48:05 INFO - Return code: 0 04:48:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:05 INFO - Reading from file tmpfile_stdout 04:48:05 INFO - Current package versions: 04:48:05 INFO - blessings == 1.6 04:48:05 INFO - blobuploader == 1.2.4 04:48:05 INFO - docopt == 0.6.1 04:48:05 INFO - manifestparser == 1.1 04:48:05 INFO - mozInstall == 1.12 04:48:05 INFO - mozcrash == 0.17 04:48:05 INFO - mozdebug == 0.1 04:48:05 INFO - mozdevice == 0.48 04:48:05 INFO - mozfile == 1.2 04:48:05 INFO - mozhttpd == 0.7 04:48:05 INFO - mozinfo == 0.9 04:48:05 INFO - mozleak == 0.1 04:48:05 INFO - mozlog == 3.2 04:48:05 INFO - moznetwork == 0.27 04:48:05 INFO - mozprocess == 0.22 04:48:05 INFO - mozprofile == 0.28 04:48:05 INFO - mozrunner == 6.11 04:48:05 INFO - mozscreenshot == 0.1 04:48:05 INFO - mozsystemmonitor == 0.3 04:48:05 INFO - moztest == 0.7 04:48:05 INFO - mozversion == 1.4 04:48:05 INFO - psutil == 3.1.1 04:48:05 INFO - requests == 1.2.3 04:48:05 INFO - wsgiref == 0.1.2 04:48:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:05 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:05 INFO - 'HOME': '/Users/cltbld', 04:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:05 INFO - 'LOGNAME': 'cltbld', 04:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:05 INFO - 'MOZ_NO_REMOTE': '1', 04:48:05 INFO - 'NO_EM_RESTART': '1', 04:48:05 INFO - 'PAGER': '/bin/cat', 04:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:05 INFO - 'PWD': '/builds/slave/test', 04:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:05 INFO - 'SHELL': '/bin/bash', 04:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:48:05 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:05 INFO - 'USER': 'cltbld', 04:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:05 INFO - 'XPC_FLAGS': '0x0', 04:48:05 INFO - 'XPC_SERVICE_NAME': '0', 04:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:48:05 INFO - Cleaning up... 04:48:05 INFO - Return code: 0 04:48:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:05 INFO - 'HOME': '/Users/cltbld', 04:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:05 INFO - 'LOGNAME': 'cltbld', 04:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:05 INFO - 'MOZ_NO_REMOTE': '1', 04:48:05 INFO - 'NO_EM_RESTART': '1', 04:48:05 INFO - 'PAGER': '/bin/cat', 04:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:05 INFO - 'PWD': '/builds/slave/test', 04:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:05 INFO - 'SHELL': '/bin/bash', 04:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:48:05 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:05 INFO - 'USER': 'cltbld', 04:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:05 INFO - 'XPC_FLAGS': '0x0', 04:48:05 INFO - 'XPC_SERVICE_NAME': '0', 04:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:05 INFO - Downloading/unpacking jsonschema 04:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:48:08 INFO - Downloading/unpacking functools32 (from jsonschema) 04:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:08 INFO - Downloading functools32-3.2.3-2.tar.gz 04:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:48:08 INFO - warning: no files found matching '*.txt' 04:48:08 INFO - no previously-included directories found matching 'build' 04:48:08 INFO - no previously-included directories found matching 'dist' 04:48:08 INFO - no previously-included directories found matching '.git*' 04:48:08 INFO - Installing collected packages: jsonschema, functools32 04:48:08 INFO - Running setup.py install for functools32 04:48:08 INFO - warning: no files found matching '*.txt' 04:48:08 INFO - no previously-included directories found matching 'build' 04:48:08 INFO - no previously-included directories found matching 'dist' 04:48:08 INFO - no previously-included directories found matching '.git*' 04:48:09 INFO - Successfully installed jsonschema functools32 04:48:09 INFO - Cleaning up... 04:48:09 INFO - Return code: 0 04:48:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:09 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 0x104122a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104128570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99d84c8620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, '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.TlCjxspFgs/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.Tfln5NEncm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:09 INFO - 'HOME': '/Users/cltbld', 04:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:09 INFO - 'LOGNAME': 'cltbld', 04:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:09 INFO - 'MOZ_NO_REMOTE': '1', 04:48:09 INFO - 'NO_EM_RESTART': '1', 04:48:09 INFO - 'PAGER': '/bin/cat', 04:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:09 INFO - 'PWD': '/builds/slave/test', 04:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:09 INFO - 'SHELL': '/bin/bash', 04:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:48:09 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:09 INFO - 'USER': 'cltbld', 04:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:09 INFO - 'XPC_FLAGS': '0x0', 04:48:09 INFO - 'XPC_SERVICE_NAME': '0', 04:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:09 INFO - Downloading/unpacking flake8 04:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:48:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:48:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:48:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:13 INFO - no previously-included directories found matching 'docs/_build' 04:48:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:48:13 INFO - Running setup.py install for pep8 04:48:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:13 INFO - no previously-included directories found matching 'docs/_build' 04:48:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:48:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:48:13 INFO - Cleaning up... 04:48:13 INFO - Return code: 0 04:48:13 INFO - Running post-action listener: _resource_record_post_action 04:48:13 INFO - Running post-action listener: _start_resource_monitoring 04:48:13 INFO - Starting resource monitoring. 04:48:13 INFO - ##### 04:48:13 INFO - ##### Finished create-virtualenv step (success) 04:48:13 INFO - ##### 04:48:13 INFO - ##### 04:48:13 INFO - ##### Running install step. 04:48:13 INFO - ##### 04:48:13 INFO - Running pre-action listener: _resource_record_pre_action 04:48:13 INFO - Running main action method: install 04:48:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:13 INFO - Reading from file tmpfile_stdout 04:48:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:13 INFO - Reading from file tmpfile_stdout 04:48:13 INFO - Output received: 04:48:13 INFO - Usage: mozinstall [options] installer 04:48:13 INFO - Options: 04:48:13 INFO - -h, --help show this help message and exit 04:48:13 INFO - -d DEST, --destination=DEST 04:48:13 INFO - Directory to install application into. [default: 04:48:13 INFO - "/builds/slave/test"] 04:48:13 INFO - --app=APP Application being installed. [default: firefox] 04:48:13 INFO - mkdir: /builds/slave/test/build/application 04:48:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:48:48 INFO - Reading from file tmpfile_stdout 04:48:48 INFO - Output received: 04:48:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:48:48 INFO - Running post-action listener: _resource_record_post_action 04:48:48 INFO - ##### 04:48:48 INFO - ##### Finished install step (success) 04:48:48 INFO - ##### 04:48:48 INFO - ##### 04:48:48 INFO - ##### Running run-tests step. 04:48:48 INFO - ##### 04:48:48 INFO - Running pre-action listener: _resource_record_pre_action 04:48:48 INFO - Running main action method: run_tests 04:48:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:48 INFO - 2016-05-31 04:48:48.236 screenresolution[1643:7900] starting screenresolution argv=screenresolution get 04:48:48 INFO - 2016-05-31 04:48:48.239 screenresolution[1643:7900] Display 0: 1600x1200x32@60 04:48:48 INFO - 2016-05-31 04:48:48.245 screenresolution[1644:7902] starting screenresolution argv=screenresolution list 04:48:48 INFO - Available Modes on Display 0 04:48:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:48 INFO - Intel Iris: 04:48:48 INFO - Chipset Model: Intel Iris 04:48:48 INFO - Type: GPU 04:48:48 INFO - Bus: Built-In 04:48:48 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:48 INFO - Vendor: Intel (0x8086) 04:48:48 INFO - Device ID: 0x0a2e 04:48:48 INFO - Revision ID: 0x0009 04:48:48 INFO - Displays: 04:48:48 INFO - Display: 04:48:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:48 INFO - Display Serial Number: 1600x1200 60 04:48:48 INFO - Main Display: Yes 04:48:48 INFO - Mirror: Off 04:48:48 INFO - Online: Yes 04:48:48 INFO - Rotation: Supported 04:48:48 INFO - Return code: 0 04:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:48 INFO - Python 2.7.3 04:48:48 INFO - Return code: 0 04:48:48 INFO - grabbing minidump binary from tooltool 04:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:48 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 0x103fc6d30>, '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 0x103deadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103fbdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:48:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:48:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:48:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:48 INFO - Return code: 0 04:48:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:48:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:48:49 INFO - Return code: 0 04:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0203', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0203 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TlCjxspFgs/Render', 04:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:49 INFO - 'HOME': '/Users/cltbld', 04:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:49 INFO - 'LOGNAME': 'cltbld', 04:48:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:49 INFO - 'MOZ_NO_REMOTE': '1', 04:48:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:49 INFO - 'NO_EM_RESTART': '1', 04:48:49 INFO - 'PAGER': '/bin/cat', 04:48:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:49 INFO - 'PWD': '/builds/slave/test', 04:48:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:49 INFO - 'SHELL': '/bin/bash', 04:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tfln5NEncm/Listeners', 04:48:49 INFO - 'TMPDIR': '/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/', 04:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:49 INFO - 'USER': 'cltbld', 04:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:49 INFO - 'XPC_FLAGS': '0x0', 04:48:49 INFO - 'XPC_SERVICE_NAME': '0', 04:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0203', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:48:49 INFO - mozversion application_buildid: 20160531030039 04:48:49 INFO - mozversion application_changeset: 864cdd00360cdf62ea5132a457ee53a17f9e31aa 04:48:49 INFO - mozversion application_display_name: Nightly 04:48:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:49 INFO - mozversion application_name: Firefox 04:48:49 INFO - mozversion application_remotingname: firefox 04:48:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:48:49 INFO - mozversion application_vendor: Mozilla 04:48:49 INFO - mozversion application_version: 49.0a1 04:48:49 INFO - mozversion platform_buildid: 20160531030039 04:48:49 INFO - mozversion platform_changeset: 864cdd00360cdf62ea5132a457ee53a17f9e31aa 04:48:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:48:49 INFO - mozversion platform_version: 49.0a1 04:48:49 INFO - using testdate: 1464695329 04:48:49 INFO - actual date: 1464695329 04:48:49 INFO - starting webserver on 'localhost:49279' 04:48:49 INFO - SUITE-START | Running 2 tests 04:48:49 INFO - TEST-START | dromaeo_css 04:48:49 INFO - operating with platform_type : mac_ 04:48:49 INFO - Initialising browser for dromaeo_css test... 04:48:49 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpDdo_88/profile http://localhost:49279/getInfo.html) 04:48:52 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 04:48:52 INFO - PROCESS | 1662 | colorDepth:24 04:48:52 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 04:48:52 INFO - PROCESS | 1662 | __metrics 04:48:52 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 04:48:53 INFO - TEST-INFO | 1662: exit 0 04:48:53 INFO - Browser initialized. 04:48:53 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:53 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpDdo_88/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:05 INFO - PROCESS | 1666 | RSS: Main: 252661760 04:49:05 INFO - PROCESS | 1666 | 04:49:55 INFO - PROCESS | 1666 | 1464695395328 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:52:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 04:52:09 INFO - PROCESS | 1666 | RSS: Main: 265035776 04:52:09 INFO - PROCESS | 1666 | 04:55:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 04:55:11 INFO - PROCESS | 1666 | RSS: Main: 276275200 04:55:11 INFO - PROCESS | 1666 | 04:58:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 04:58:14 INFO - PROCESS | 1666 | RSS: Main: 278380544 04:58:14 INFO - PROCESS | 1666 | 05:01:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 05:01:16 INFO - PROCESS | 1666 | RSS: Main: 279666688 05:01:16 INFO - PROCESS | 1666 | 05:04:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 05:04:20 INFO - PROCESS | 1666 | RSS: Main: 283537408 05:04:20 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 05:07:23 INFO - PROCESS | 1666 | RSS: Main: 283770880 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | __start_tp_report 05:07:23 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:07:23 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:07:23 INFO - PROCESS | 1666 | |i|pagename|runs| 05:07:23 INFO - PROCESS | 1666 | |0;dojo.html;2885.4661887550806;3458.2391873787756;3464.844082016309;3507.5966263879645;3514.455259434787;5644.266245388099;5650.576206784487;5736.512396446307;5742.28221472316;5771.711414429272;5610.158476228567;5715;5770.740316685748;5777.942220577793;5914.201582786322;4190.685698572604;4241.4698162729655;4891.779869905863;5209.817656382027;5304.70824104676;5178.4562620924635;5293.68237905725;5345.412004679482;5347.41178470368;5372.516473517383;5036.093503169428;5218.739063046833;5241.502057304588;5273.578113750891;5318.388385335697;4415.580519850609;5052.621053420971;5071.695698258116;5090.770915308783;5114.977004599058;4051.8784436466954;4997.100521906055;5034.622403319766;5063.772130254148;5076.314697515825;5259.053370393328;5512.476314750133;5529.502344788988;5588.385277619459;5702.6293290935955;5460.726963651841;6162.106494558308;6260.248770147611;6266.185395898549;6292.590981586182;6246.702437956066;6290.716917738713;6292.244930608311;6293.590916590407;6306.306306306303;5698.7435565399655;5788.421157884178;5801.564882633819;5863.970680146572;5903.645781253139;6336.556441049082;6338.968305158444;6357.904631430532;6358.586691865014;6368.490520758328;6137.877242455215;6154.261488621394;6155.138280640714;6164.938350616437;6165.352637972948;6069.332373438918;6124.350421260821;6147.524594097385;6163.075538669146;6200.224971878515;6071.089336599457;6108.5418593605655;6181.505479561624;6187.659678717714;6194.907076393858;6241.251049874044;6254.155688981929;6274.090256912813;6283.560150789493;6289.591176573508;4689.343491911135;4963.70217786934;4982.476839931827;5021.974890125526;5047.596192304607;4442.400275962768;4456.059001059285;4456.153330867125;4495.910081798345;4496.7301961882395;5788.131780232999;5849.210356601804;5852.063669812809;5863.560232982543;5868.589198756132;6132.845047373359;6155;6194.628322300677;6275.403836635513;6288.213973253343;5797.826065218051;5806.1000544915605;5816.476517113396;5826.650400975955;5849.3858144894175;5343.225232341289;5393.27190829233;5420.91868621971;5435.755391007414;5436.401995780461;6285.371462853678;6287.654179020106;6325.304216536178;6398.072279519537;6410.935890641033;6278.968605157128;6297.4017468340435;6315.778947736807;6319.2732835722645;6330.8100756977365;6211.9068213975925;6243.43809057187;6389.7763578274535;6404.167458230587;6418.711157997808;6110.6195130195665;6161.599496032743;6198.132261483486;6221.782237621662;6231.065340199007;5180.792768289226;5244.606654500852;6254.874902502015;6269.404406581368;6275.435210831047;5944.4352786485215;6061.060535616986;6071.696415179312;6113.877722445615;6126.693665316628;6020.578559500968;6086.908696369469;6099.634021958696;6156.630602163885;6250.031245157008;5991.550633702403;6025.698715064142;6032.668203248863;6032.819015429544;6066.514678825772;5048.949510504848;6020.187274718032;6055.909161362495;6160.291566469913;6194.783182588588;4556.681032327838;6049.911016017159;6070.998285283055;6074.967255566476;6080.665563394055 05:07:23 INFO - PROCESS | 1666 | |1;ext.html;502.4623652691619;510.22445882258967;515.4536191636865;547.1655725019347;554.958378121641;30423.39153216937;42746.93132671677;42770.786146069266;42837.78581107098;42889.571104289025;41709.7914510427;42378;42408.36387454191;42422.78788606053;42532;9584.56869440875;9830.066143716336;9833.459159746211;9870.703878883609;9872.506374681238;9700.320977531575;9752.756181095458;9782.853257201148;9822;9829.4102353859;9818.263630227726;9841.852372214387;9851.704448866545;9853.014698530125;9870.309078364517;8395.580220988957;9801.852972205423;9808.90191098087;9848.55681494331;9854.260930430175;9623.615055397775;9758.804823903482;9765.267604929659;9767.218622510181;9769.755756106155;40548.18903621911;40833.59166408327;40933.38599921003;41234.793826030975;41467.585324146676;3312.5362449257123;3517.2086280586664;3632.5096112024803;3733.701303895682;3855.8457661693496;32081.358372832412;32678;33019.009429717145;33229.3354132916;33761;3235.9352812943844;3280.5243239730344;3330.167458135466;3342.615599206098;3443.2941247044546;518387;528616;531422;532943;535170;70886;71493;71739.6413017942;71809.40952952331;71860;37173.25653486915;37535.36686562051;37994.43008354877;38981.6101838978;39003;14359.210243436712;17636;17792.555186120193;17792.822071779374;17801.287948481917;13108.475660973454;13245.470181192644;13276.668083297995;13278.203307801563;13305.40125694346;19392.90303548473;19923.20307187725;20113.899430502755;21847.563048738935;22131.336059917907;7725.49784264021;9101;9231.399959002778;9248.583813728399;9254.583543740548;894.8970868350223;919.3472634429496;1000.9290059636265;1016.7966406718685;1032.4579595712312;31850.2037449061;33063.83468082644;33438.331233375684;34129.829350853084;34199.4870076949;994.7267497603079;1023.4013102335068;1055.0715370473933;1084.7017070305728;1122.8820973797797;14746.336414861356;16455.917720411322;16561.01267847866;16634.6673066538;16743.07913064793;688540;690091;693573;696301;699200;62928.685356572925;63147.368526314145;63643.04535431972;63650.726985460955;63695.36304636987;1409.4080486195821;1444.4511085787333;1467.508384691119;1476.9113853168844;1478.0318891126115;27487.90048398042;30112.698873010988;30613.23466913345;31098.84450577823;31973.5974915625;55473.1679024823;56086.87826243534;56316.71841640765;56346.15480767874;56403.43596564146;500830;507033.9296606987;512810;514821;519860;27604.309892252855;28037.299067523476;28547;28569.28576785597;28584.714152859036;49728.25407618816;50157.74921125371;50323.49676503188;51369.7431512855;51522.96954060973;39078.023049423995;41275.1744965105;41509.79245103755;41877.11195089238;42271.78864105783;35326.11684707903;36554.903352899455;40423.59576404198;40592;40964.77105686834;39762;40271;41003;41033.589664102976;42561.574384255764;33764.15589610279;36592.90221293365;36738.63261367459;36850;36909.26181476409;24755.876220618782;26399.34001649974;26616.201513954613;26811.525366105052;26947.92208311724 05:07:23 INFO - PROCESS | 1666 | |2;jquery.html;376.5895174260056;382.0192302909415;384.60577907644665;393.8660855309194;404.2965240481563;18923.810761892393;19922;20863.95680215989;20865.582688346225;21290.361289161312;11716.882831171706;11923.761524769521;12110.728373520786;12235.816462753066;12241.693957651041;5427.213054107162;5596.776128954837;5646.943530564683;5649.887002259952;5667.979763642543;3275.623303320113;5864.76540938362;6175.969120154393;6188.071789231606;6251.4061164189325;5557.360903496109;5632.662040277596;6228.159198508211;6235.7505699771955;6252.218472690914;5864.648121112747;5980.222571065756;5984.192134061889;6098.908516372258;6104.114903339036;5929.110633404981;5957.076653118774;5982.940170598282;5986.5210783137245;5988.461038506512;13405.530806421728;13629.659258518517;19704.80295197044;25846.87076564624;25870.353241168934;1962.7252184694155;2102.5374417628095;2137.8396620253543;2147.044565168501;2218.689383486313;9853.90146098537;10071.748206294827;10382.22133339995;11309.321440713584;15902.522924312289;1811.3388613156235;1834.5688763140786;1843.6036252205706;1868.4394681595468;1911.3405874973112;102312;117869.41065294619;121113.78886211113;124069.75930240762;124382.37808810988;11524.078073754246;11654.766904662029;11676.94161529187;11727.237729547724;11828.822567661491;14936.775948360782;14947;14957.102573845605;15029.398824047134;15033.398664053315;7205.531640443459;8536.274416674652;8738.432001919982;8753.212210901049;8813.118688131135;6886.724531018804;7006.9649651751415;7425.554466732014;8642.827143457096;8724.345674074342;3923.6664883484655;4514.142312960527;4621.861344159681;4630.58324750774;4678.128748500627;1490.3293517916982;1719.9914000429917;1795.2729144696418;1940.932067377635;1972.477293517219;840.399114633028;927.8561822917459;1007.2445665750687;1020.4081632653043;1071.796358691846;20908.954552272324;20920.134994341905;21368.572628547343;21625.918704064734;21685.457863553223;872.2324354568067;911.4759013567225;937.5687183895484;959.7024922274118;973.8782652168479;7989;11286.887131128584;11430.085593152695;11437.942810285895;11506.252093613888;574213;584330;597102;598787;600254.9987250036;127328;127850.72149278574;129795.35102324617;131369;131785.68214317926;726.7529040126383;938.6339327662217;941.5010044676333;941.9811603767886;967.2117224462041;18728.438146855617;18916.716249256;19035.71446428277;19091;19313.9377334248;34748.82625586856;52848.67878303073;53506.92986140333;53590.464095360105;54031.459685402646;63268.36731632809;76448.23551764633;76487;80366;83094.58452707698;7034.47241456903;7469.663865125974;7498.550086994798;7643.923560764321;7734.342580880463;10949.781004380027;12692.238465692088;12844.35778211067;13204.867951320364;13260.933695331461;10773.10463463894;11035.889641103808;11361.943190283995;11531.481083351102;11569.479373428048;10604.469776511298;10883.292585981886;11163.162762792985;11531.654050378502;11585.594504192313;11722.062235021349;11767.705807354885;11864.644060678194;11973.982211511771;12055.276683399024;6892.896606551006;7434.479586428898;7605.429592780453;8194.385421093322;8625.611847467004;8368.163183681583;10587.629432970118;11484.598039068593;11558.190926635054;11631.53473861036 05:07:23 INFO - PROCESS | 1666 | |3;mootools.html;1578;1605.8233594304625;1828.7165489349147;1894.564250222449;1938.2828353509203;8006.399520035991;8461.53461559614;8474.576271186446;8966.641334346627;8974.596143173556;8658.480491170534;8709.86935195971;8739.344549158823;8775.95612021939;8784.429012114208;2329.685492458522;2351.7295511016196;2360.634101714237;2365.3731761083327;2372.193454225563;2297.5060362022164;2302.5970455170363;2321.9651705224437;2334.8482348647367;2341;2336.894839732216;2344.531093781242;2353.576356255873;2357.634566642165;2358.6462030695448;2310.514791893704;2326.767323267668;2328.639060945556;2336.123953517431;2344.3553022918663;2331.9300420987397;2332.8685587490054;2333.0551126793507;2335.2293743064747;2338;8703.086175951497;8786.824263514758;8842.425242359226;8850.955745221232;8860.202581767608;5101.6173786965755;5119.25770763241;5263.947360526384;5269.367675878881;5315.548178404841;3425.5033020212177;3537.6462353764673;3573.0531409176588;3575.66031227033;3645.453182022691;835.3859912963967;956.4022485946283;1003.4631472162358;1054.9683509494728;1065.690949624608;10922.508487118099;11262.493187806569;11339.433028348632;11355.602553910656;11542.36516991565;4053.8175782089875;4633.4208223972;4706.670533062721;4724.291356296514;4743.359646447685;9837.950810246046;9879.506024698736;9893.703188904345;9897.703068907944;9980.500974951223;4442.267025940683;5238.659487133325;5292.973535132377;5328.094223981932;5342.732863356817;4382.408374869352;4694.554017368345;4852.053849499384;4863.927041094386;4867.537583929521;3944.881653550398;4013.8795836124964;4035.1929614076716;4080.6939479539155;4135.110951145518;1170.9180357375071;1176.6293617510457;1179.292424545266;1182.3083496154675;1245.2404033539533;751.2637615137196;815.6003558256411;889.6841621224502;912.6851236323457;919.6229545886155;4529.547045295444;5607.607467477319;5827.737751801179;5897.32180799205;6013.759449621966;907.5825120444538;916.3539704508306;969.8157350103598;970.1511177719495;991.5141580625442;3824.0631045394057;3899.66852817508;3963.1875465529497;4062.837486500566;4098.7335823172;10431.426271554985;10734.78530429387;10771.78456430867;10777.83833242502;10812.35125892449;9021.368504204775;10296.330738501973;10813.945930270298;10823.29648572856;10870.347779133277;7322.901564765328;8010.359171266285;8149.185081491803;8170.509769413854;8359.038710548364;5788.739506722123;5895.174675545341;5909.113632955091;5925.140854576149;5937.465628093492;9740.366876153028;10117.190624749855;10159.187265018632;10163.542640581043;10189.74525636865;9956.054174853378;10239.948800255952;10360.222983276133;10383.532741026824;10410.271281010257;6096.695165241809;6574.605523668595;7041.155061392461;7149.249328820607;7222.169450513048;8463.873041904253;8468;8469.74590762278;8567.186117318844;8567.74296771098;7426.517276377018;7586.772396828104;7611.467197296136;7629.3133617974645;7712.421568382281;7287.27127287267;7340.853182936418;7341.74303899361;7396.0754905636795;7527.209642987407;7076.0801095857205;7111.502194846311;7129.964350178216;7133.8929916050265;7159.463040271896;4466.7939656293265;4835.1296766580945;4907;5000.824971125993;5014.172661510956;4946.406431228275;4970.453250142409;5025.698458092526;5138.177891537336;5143.125668636266 05:07:23 INFO - PROCESS | 1666 | |4;prototype.html;300.5416739472306;309.9085769697939;316.12906442750227;319.8784461904476;321.1489552685383;4966.577840883525;5388.548210564194;5457.918131228034;5577.525910297624;5633.295838020247;5023.572996295319;5376.499927533696;5499.587530935186;5540.091451987699;5561.63849349792;3932.9970857431317;4166.354215096665;4170.68719846012;4192.350185721218;4204.579542045802;4006.5793091725386;4008.3586626139822;4157.6072015874715;4173.227952828721;4212.630894959135;3683.686886614641;3786.924261514768;4041.3533834586324;4106.404571337154;4109.856155034575;3469.9479507807278;3648.154439537165;3973.7019723520707;3992.820323085439;4151.8962025949295;3043.786934914535;3057.541368794676;3329.816860072695;3577.0520811984843;3603.648631763089;4350.282203436429;5327.573794096502;5352.785888564453;5413.863088751367;5423.484768946943;4180.958190418118;4730.62155027597;4741.905161896777;4770.570648641604;4771.308160316769;5474.069408200616;5823.767808866078;5883.6465413834385;5897.32180799205;6029.758809647609;3034.8179109253515;3155.7475401968018;3943.605639436062;3952.3281042222893;3954.169624378884;4973.875653108665;5215.269862219292;6206.565540412173;6224.564280500367;6241.781537646206;3085.568020477135;3539.876104336336;3946.9802650987135;3983.721139520264;4005.4192142139227;3405.6083550391527;3429.868143512635;4040.5151381834366;4048.4939382577177;4070.898227544335;4282.122164956177;4525.479569849444;4653.464851542049;4687.929681054787;4723.968693461776;4959.876003099951;5597.80407685729;5902.291724993029;5904.350521442638;5954.166416701665;1332.6734949937133;1378.7931810228547;1384.5292600515672;1387.1607677355257;1397.329281944652;725.9310365515267;735.8638651849425;740.3336996702902;820.6052087148386;832.076395201326;4293.273771155276;4335.436393268855;4450.3991961085485;4484.730916145042;4714.905701885943;4220.775974967225;4259.574042595716;5039.689680682975;5156.9887743779955;5182.585393168538;3965.4138344661787;4069.4099355593867;4536.119564076769;4592.265237561974;4636.35091087248;2436.0012394918;2460.175841093218;2673.791450259696;2757.8279231326605;2759.4205216904274;4624.653151013687;4646.582792248397;4720;4808.879778005507;4866.5376789205675;4479.664025198123;4568.657350698711;4569.240842275682;4818.445878723992;5219.6689844089515;4049.959500405017;4533.775880512243;4604.217283061955;4870.683405578626;5335.973320133375;4472.731636101845;4588.357629931746;5059.507445303718;5159.9102363542825;5167;4232.153569286216;4705.411823522059;4850.927236091391;5015.949840501694;5110.48895110486;4404.4494438195225;4725.362076119817;4748.180596694427;4934.802607895787;5208.479152084761;3200.2959348943195;3321.5017747338093;3403.676650718178;3501.7373696972318;3505.0021487751737;4496.459500382273;4498.910021799611;4690.648201384978;5066.695998240118;5136.691798492103;4931.816364072525;5027.622928280321;5814.3604203538425;5848.64908105515;5886.38192989747;4817.7350245736825;4955.008998200302;5698.922581805944;5705.600607957404;5814.825555233349;4862.270659401118;5443.618946673853;5728.684922329312;5730.424133363358;5784.2191304175085;5259.47405259471;5838.795642152504;5844.970775146097;5886.440788125121;5895.41045895407;5083.440821509557;5668.574856885668;5702;5745.74144163522;5748.252727145445 05:07:23 INFO - PROCESS | 1666 | |5;yui.html;673.457866417534;775.1550809617517;782.4092809928507;853.4495250563383;863.8272345530894;14672.486462973799;15033.173175475344;15228.933974621781;15233.695326093486;15573.29920153593;15927.601809954756;15985;15997.280122394492;15998.360065597362;16013.11927843968;698.6227437183915;857.8327226190895;894.575076838503;895.0288936503902;903.3044555692113;882.9072947340533;887.9911200888006;888.0542222533014;890.2566357091836;890.5057692980394;935.5041827831226;944.3295260365114;944.5418971798706;945.4469135555709;946.1390134977172;908.14180599333;928.4846909964966;935.5369092299355;936.6159874451477;938.8497840345547;674.7806962737081;681.7034589953386;696.5646470955653;697.9616121113337;698.6087790837097;14847.554573362817;14931.10413375201;14934.551963441114;14952.47666331684;14975;13689.178649280975;13696.315184240848;13701.177929324273;13781.242031688253;13827.170369777845;11605.24565903222;11767.588134415255;11956.983656389217;11969.186660780484;12219.694507637292;3722.813859307024;3745.232227393378;3782.224643947984;3828.272628200633;3927.8428862845453;89744.55127724385;90702;90714.54642726744;91538.62692059625;91604.54197729036;10454.52954617044;10659.307145035547;10734.946325268324;10739.946300268604;10926.836097458545;20040.89979550093;20404.897975510026;20544.691829622567;20884.060217290258;21382.679259811117;6877.6217308048535;7079.89380159298;7113;7987.68049278034;8034.718784842503;3795.373763329075;4148.668106551469;4555.2256116460285;4578.793954272066;4589.426321709786;3284.981655686745;3307.3881331953667;3369.309291595217;3370.8651653934057;3376.037829218661;551.4457969740386;630.3979699387074;631.5831551176202;640.69887153038;641.0704478506185;583.3408248678966;607.2379164149053;623.6975067092457;699.989500157498;701.5002381330394;7619.390448764086;8523;8868.802711633976;9503.619855205714;9532.168700535007;594.878049999749;609.871926895357;656.5830697507117;677.8237658208901;679.2867489136387;4299.849505267301;4475.239209334421;4497.302918047708;4501.617362524222;4506.6406617395805;92023;92727;93077.5346123265;93674;93952;64325.67837160878;80584.59707701541;81809;83135;84123;66818;67797.66101169462;67991.66004169948;73918.63040684762;74930.62534687291;7676.692932282646;9215.545303856445;9292.6282948684;9304;9397.624095036123;42242.78878605587;44093.33859992038;44138;45652.771736141105;46127.308090378;61144;61453;64698.02952955615;67158.66420668061;67732.661336693;12311.692207694521;12395.566155184886;12624.305663188612;12974.091813572959;13112.344382780631;14060.156390616596;14361.425542978164;14405.06367086136;14412.42350306018;14674.63313417173;13507.122037067558;13571.796423053465;13620.523281685093;13793.793093103812;13842.515511957034;13263.734725305632;13451.977736842884;13583.592492225249;13763.174209547458;13881.097728647606;10838.783224335626;12823.422945967268;12935.482580697044;12973.416196271379;13528.86471135276;9853.76827896513;11020.338779673246;11182.32906025641;11193.832092518456;11385.658430247106;9047.909520904706;11093.223474356717;11285.37930413835;11304.886951130384;11333.320000799979 05:07:23 INFO - PROCESS | 1666 | __end_tp_report 05:07:23 INFO - PROCESS | 1666 | __start_cc_report 05:07:23 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,427 05:07:23 INFO - PROCESS | 1666 | __end_cc_report 05:07:23 INFO - PROCESS | 1666 | __startTimestamp1464696443668__endTimestamp 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:07:23 INFO - PROCESS | 1666 | Number of tests: 6 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5705.17 Median:6020.19 stddev:693.97 (11.5%) stddev-sans-first:659.91 05:07:23 INFO - PROCESS | 1666 | Values: 2885.5 3458.2 3464.8 3507.6 3514.5 5644.3 5650.6 5736.5 5742.3 5771.7 5610.2 5715.0 5770.7 5777.9 5914.2 4190.7 4241.5 4891.8 5209.8 5304.7 5178.5 5293.7 5345.4 5347.4 5372.5 5036.1 5218.7 5241.5 5273.6 5318.4 4415.6 5052.6 5071.7 5090.8 5115.0 4051.9 4997.1 5034.6 5063.8 5076.3 5259.1 5512.5 5529.5 5588.4 5702.6 5460.7 6162.1 6260.2 6266.2 6292.6 6246.7 6290.7 6292.2 6293.6 6306.3 5698.7 5788.4 5801.6 5864.0 5903.6 6336.6 6339.0 6357.9 6358.6 6368.5 6137.9 6154.3 6155.1 6164.9 6165.4 6069.3 6124.4 6147.5 6163.1 6200.2 6071.1 6108.5 6181.5 6187.7 6194.9 6241.3 6254.2 6274.1 6283.6 6289.6 4689.3 4963.7 4982.5 5022.0 5047.6 4442.4 4456.1 4456.2 4495.9 4496.7 5788.1 5849.2 5852.1 5863.6 5868.6 6132.8 6155.0 6194.6 6275.4 6288.2 5797.8 5806.1 5816.5 5826.7 5849.4 5343.2 5393.3 5420.9 5435.8 5436.4 6285.4 6287.7 6325.3 6398.1 6410.9 6279.0 6297.4 6315.8 6319.3 6330.8 6211.9 6243.4 6389.8 6404.2 6418.7 6110.6 6161.6 6198.1 6221.8 6231.1 5180.8 5244.6 6254.9 6269.4 6275.4 5944.4 6061.1 6071.7 6113.9 6126.7 6020.6 6086.9 6099.6 6156.6 6250.0 5991.6 6025.7 6032.7 6032.8 6066.5 5048.9 6020.2 6055.9 6160.3 6194.8 4556.7 6049.9 6071.0 6075.0 6080.7 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:71705.14 Median:41134.19 stddev:156009.15 (379.3%) stddev-sans-first:156355.20 05:07:23 INFO - PROCESS | 1666 | Values: 502.5 510.2 515.5 547.2 555.0 30423.4 42746.9 42770.8 42837.8 42889.6 41709.8 42378.0 42408.4 42422.8 42532.0 9584.6 9830.1 9833.5 9870.7 9872.5 9700.3 9752.8 9782.9 9822.0 9829.4 9818.3 9841.9 9851.7 9853.0 9870.3 8395.6 9801.9 9808.9 9848.6 9854.3 9623.6 9758.8 9765.3 9767.2 9769.8 40548.2 40833.6 40933.4 41234.8 41467.6 3312.5 3517.2 3632.5 3733.7 3855.8 32081.4 32678.0 33019.0 33229.3 33761.0 3235.9 3280.5 3330.2 3342.6 3443.3 518387.0 528616.0 531422.0 532943.0 535170.0 70886.0 71493.0 71739.6 71809.4 71860.0 37173.3 37535.4 37994.4 38981.6 39003.0 14359.2 17636.0 17792.6 17792.8 17801.3 13108.5 13245.5 13276.7 13278.2 13305.4 19392.9 19923.2 20113.9 21847.6 22131.3 7725.5 9101.0 9231.4 9248.6 9254.6 894.9 919.3 1000.9 1016.8 1032.5 31850.2 33063.8 33438.3 34129.8 34199.5 994.7 1023.4 1055.1 1084.7 1122.9 14746.3 16455.9 16561.0 16634.7 16743.1 688540.0 690091.0 693573.0 696301.0 699200.0 62928.7 63147.4 63643.0 63650.7 63695.4 1409.4 1444.5 1467.5 1476.9 1478.0 27487.9 30112.7 30613.2 31098.8 31973.6 55473.2 56086.9 56316.7 56346.2 56403.4 500830.0 507033.9 512810.0 514821.0 519860.0 27604.3 28037.3 28547.0 28569.3 28584.7 49728.3 50157.7 50323.5 51369.7 51523.0 39078.0 41275.2 41509.8 41877.1 42271.8 35326.1 36554.9 40423.6 40592.0 40964.8 39762.0 40271.0 41003.0 41033.6 42561.6 33764.2 36592.9 36738.6 36850.0 36909.3 24755.9 26399.3 26616.2 26811.5 26947.9 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34361.62 Median:21655.69 stddev:98800.39 (456.2%) stddev-sans-first:99044.59 05:07:23 INFO - PROCESS | 1666 | Values: 376.6 382.0 384.6 393.9 404.3 18923.8 19922.0 20864.0 20865.6 21290.4 11716.9 11923.8 12110.7 12235.8 12241.7 5427.2 5596.8 5646.9 5649.9 5668.0 3275.6 5864.8 6176.0 6188.1 6251.4 5557.4 5632.7 6228.2 6235.8 6252.2 5864.6 5980.2 5984.2 6098.9 6104.1 5929.1 5957.1 5982.9 5986.5 5988.5 13405.5 13629.7 19704.8 25846.9 25870.4 1962.7 2102.5 2137.8 2147.0 2218.7 9853.9 10071.7 10382.2 11309.3 15902.5 1811.3 1834.6 1843.6 1868.4 1911.3 102312.0 117869.4 121113.8 124069.8 124382.4 11524.1 11654.8 11676.9 11727.2 11828.8 14936.8 14947.0 14957.1 15029.4 15033.4 7205.5 8536.3 8738.4 8753.2 8813.1 6886.7 7007.0 7425.6 8642.8 8724.3 3923.7 4514.1 4621.9 4630.6 4678.1 1490.3 1720.0 1795.3 1940.9 1972.5 840.4 927.9 1007.2 1020.4 1071.8 20909.0 20920.1 21368.6 21625.9 21685.5 872.2 911.5 937.6 959.7 973.9 7989.0 11286.9 11430.1 11437.9 11506.3 574213.0 584330.0 597102.0 598787.0 600255.0 127328.0 127850.7 129795.4 131369.0 131785.7 726.8 938.6 941.5 942.0 967.2 18728.4 18916.7 19035.7 19091.0 19313.9 34748.8 52848.7 53506.9 53590.5 54031.5 63268.4 76448.2 76487.0 80366.0 83094.6 7034.5 7469.7 7498.6 7643.9 7734.3 10949.8 12692.2 12844.4 13204.9 13260.9 10773.1 11035.9 11361.9 11531.5 11569.5 10604.5 10883.3 11163.2 11531.7 11585.6 11722.1 11767.7 11864.6 11974.0 12055.3 6892.9 7434.5 7605.4 8194.4 8625.6 8368.2 10587.6 11484.6 11558.2 11631.5 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5670.67 Median:4958.43 stddev:3180.82 (64.1%) stddev-sans-first:3174.88 05:07:23 INFO - PROCESS | 1666 | Values: 1578.0 1605.8 1828.7 1894.6 1938.3 8006.4 8461.5 8474.6 8966.6 8974.6 8658.5 8709.9 8739.3 8776.0 8784.4 2329.7 2351.7 2360.6 2365.4 2372.2 2297.5 2302.6 2322.0 2334.8 2341.0 2336.9 2344.5 2353.6 2357.6 2358.6 2310.5 2326.8 2328.6 2336.1 2344.4 2331.9 2332.9 2333.1 2335.2 2338.0 8703.1 8786.8 8842.4 8851.0 8860.2 5101.6 5119.3 5263.9 5269.4 5315.5 3425.5 3537.6 3573.1 3575.7 3645.5 835.4 956.4 1003.5 1055.0 1065.7 10922.5 11262.5 11339.4 11355.6 11542.4 4053.8 4633.4 4706.7 4724.3 4743.4 9838.0 9879.5 9893.7 9897.7 9980.5 4442.3 5238.7 5293.0 5328.1 5342.7 4382.4 4694.6 4852.1 4863.9 4867.5 3944.9 4013.9 4035.2 4080.7 4135.1 1170.9 1176.6 1179.3 1182.3 1245.2 751.3 815.6 889.7 912.7 919.6 4529.5 5607.6 5827.7 5897.3 6013.8 907.6 916.4 969.8 970.2 991.5 3824.1 3899.7 3963.2 4062.8 4098.7 10431.4 10734.8 10771.8 10777.8 10812.4 9021.4 10296.3 10813.9 10823.3 10870.3 7322.9 8010.4 8149.2 8170.5 8359.0 5788.7 5895.2 5909.1 5925.1 5937.5 9740.4 10117.2 10159.2 10163.5 10189.7 9956.1 10239.9 10360.2 10383.5 10410.3 6096.7 6574.6 7041.2 7149.2 7222.2 8463.9 8468.0 8469.7 8567.2 8567.7 7426.5 7586.8 7611.5 7629.3 7712.4 7287.3 7340.9 7341.7 7396.1 7527.2 7076.1 7111.5 7130.0 7133.9 7159.5 4466.8 4835.1 4907.0 5000.8 5014.2 4946.4 4970.5 5025.7 5138.2 5143.1 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4336.80 Median:4689.29 stddev:1350.42 (28.8%) stddev-sans-first:1319.79 05:07:23 INFO - PROCESS | 1666 | Values: 300.5 309.9 316.1 319.9 321.1 4966.6 5388.5 5457.9 5577.5 5633.3 5023.6 5376.5 5499.6 5540.1 5561.6 3933.0 4166.4 4170.7 4192.4 4204.6 4006.6 4008.4 4157.6 4173.2 4212.6 3683.7 3786.9 4041.4 4106.4 4109.9 3469.9 3648.2 3973.7 3992.8 4151.9 3043.8 3057.5 3329.8 3577.1 3603.6 4350.3 5327.6 5352.8 5413.9 5423.5 4181.0 4730.6 4741.9 4770.6 4771.3 5474.1 5823.8 5883.6 5897.3 6029.8 3034.8 3155.7 3943.6 3952.3 3954.2 4973.9 5215.3 6206.6 6224.6 6241.8 3085.6 3539.9 3947.0 3983.7 4005.4 3405.6 3429.9 4040.5 4048.5 4070.9 4282.1 4525.5 4653.5 4687.9 4724.0 4959.9 5597.8 5902.3 5904.4 5954.2 1332.7 1378.8 1384.5 1387.2 1397.3 725.9 735.9 740.3 820.6 832.1 4293.3 4335.4 4450.4 4484.7 4714.9 4220.8 4259.6 5039.7 5157.0 5182.6 3965.4 4069.4 4536.1 4592.3 4636.4 2436.0 2460.2 2673.8 2757.8 2759.4 4624.7 4646.6 4720.0 4808.9 4866.5 4479.7 4568.7 4569.2 4818.4 5219.7 4050.0 4533.8 4604.2 4870.7 5336.0 4472.7 4588.4 5059.5 5159.9 5167.0 4232.2 4705.4 4850.9 5015.9 5110.5 4404.4 4725.4 4748.2 4934.8 5208.5 3200.3 3321.5 3403.7 3501.7 3505.0 4496.5 4498.9 4690.6 5066.7 5136.7 4931.8 5027.6 5814.4 5848.6 5886.4 4817.7 4955.0 5698.9 5705.6 5814.8 4862.3 5443.6 5728.7 5730.4 5784.2 5259.5 5838.8 5845.0 5886.4 5895.4 5083.4 5668.6 5702.0 5745.7 5748.3 05:07:23 INFO - PROCESS | 1666 | 05:07:23 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18938.91 Median:4584.11 stddev:26087.22 (569.1%) stddev-sans-first:26124.35 05:07:23 INFO - PROCESS | 1666 | Values: 673.5 775.2 782.4 853.4 863.8 14672.5 15033.2 15228.9 15233.7 15573.3 15927.6 15985.0 15997.3 15998.4 16013.1 698.6 857.8 894.6 895.0 903.3 882.9 888.0 888.1 890.3 890.5 935.5 944.3 944.5 945.4 946.1 908.1 928.5 935.5 936.6 938.8 674.8 681.7 696.6 698.0 698.6 14847.6 14931.1 14934.6 14952.5 14975.0 13689.2 13696.3 13701.2 13781.2 13827.2 11605.2 11767.6 11957.0 11969.2 12219.7 3722.8 3745.2 3782.2 3828.3 3927.8 89744.6 90702.0 90714.5 91538.6 91604.5 10454.5 10659.3 10734.9 10739.9 10926.8 20040.9 20404.9 20544.7 20884.1 21382.7 6877.6 7079.9 7113.0 7987.7 8034.7 3795.4 4148.7 4555.2 4578.8 4589.4 3285.0 3307.4 3369.3 3370.9 3376.0 551.4 630.4 631.6 640.7 641.1 583.3 607.2 623.7 700.0 701.5 7619.4 8523.0 8868.8 9503.6 9532.2 594.9 609.9 656.6 677.8 679.3 4299.8 4475.2 4497.3 4501.6 4506.6 92023.0 92727.0 93077.5 93674.0 93952.0 64325.7 80584.6 81809.0 83135.0 84123.0 66818.0 67797.7 67991.7 73918.6 74930.6 7676.7 9215.5 9292.6 9304.0 9397.6 42242.8 44093.3 44138.0 45652.8 46127.3 61144.0 61453.0 64698.0 67158.7 67732.7 12311.7 12395.6 12624.3 12974.1 13112.3 14060.2 14361.4 14405.1 14412.4 14674.6 13507.1 13571.8 13620.5 13793.8 13842.5 13263.7 13452.0 13583.6 13763.2 13881.1 10838.8 12823.4 12935.5 12973.4 13528.9 9853.8 11020.3 11182.3 11193.8 11385.7 9047.9 11093.2 11285.4 11304.9 11333.3 05:07:23 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:07:23 INFO - PROCESS | 1666 | 05:07:24 INFO - TEST-INFO | 1666: exit 0 05:07:24 INFO - TEST-OK | dromaeo_css | took 1114290ms 05:07:24 INFO - TEST-START | kraken 05:07:24 INFO - operating with platform_type : mac_ 05:07:24 INFO - Initialising browser for kraken test... 05:07:24 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpJH7zh_/profile http://localhost:49279/getInfo.html) 05:07:27 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:07:27 INFO - PROCESS | 1673 | colorDepth:24 05:07:27 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:07:27 INFO - PROCESS | 1673 | __metrics 05:07:27 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 05:07:27 INFO - TEST-INFO | 1673: exit 0 05:07:27 INFO - Browser initialized. 05:07:27 INFO - Running cycle 1/1 for kraken test... 05:07:27 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/T/tmpJH7zh_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:39 INFO - PROCESS | 1675 | RSS: Main: 252403712 05:07:39 INFO - PROCESS | 1675 | 05:07:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:43 INFO - PROCESS | 1675 | RSS: Main: 265072640 05:07:43 INFO - PROCESS | 1675 | 05:07:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 05:07:46 INFO - PROCESS | 1675 | RSS: Main: 269168640 05:07:46 INFO - PROCESS | 1675 | 05:07:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 05:07:50 INFO - PROCESS | 1675 | RSS: Main: 269729792 05:07:50 INFO - PROCESS | 1675 | 05:07:52 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 05:07:53 INFO - PROCESS | 1675 | RSS: Main: 270663680 05:07:53 INFO - PROCESS | 1675 | 05:07:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:56 INFO - PROCESS | 1675 | RSS: Main: 271220736 05:07:56 INFO - PROCESS | 1675 | 05:07:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:59 INFO - PROCESS | 1675 | RSS: Main: 271450112 05:07:59 INFO - PROCESS | 1675 | 05:08:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:03 INFO - PROCESS | 1675 | RSS: Main: 271728640 05:08:03 INFO - PROCESS | 1675 | 05:08:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 05:08:07 INFO - PROCESS | 1675 | RSS: Main: 271810560 05:08:07 INFO - PROCESS | 1675 | 05:08:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:08:09 INFO - PROCESS | 1675 | RSS: Main: 271724544 05:08:09 INFO - PROCESS | 1675 | 05:08:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:12 INFO - PROCESS | 1675 | RSS: Main: 271724544 05:08:12 INFO - PROCESS | 1675 | 05:08:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:15 INFO - PROCESS | 1675 | RSS: Main: 272044032 05:08:15 INFO - PROCESS | 1675 | 05:08:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:18 INFO - PROCESS | 1675 | RSS: Main: 272134144 05:08:18 INFO - PROCESS | 1675 | 05:08:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:22 INFO - PROCESS | 1675 | RSS: Main: 272003072 05:08:22 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 05:08:24 INFO - PROCESS | 1675 | RSS: Main: 272801792 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | __start_tp_report 05:08:24 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 05:08:24 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 05:08:24 INFO - PROCESS | 1675 | |i|pagename|runs| 05:08:24 INFO - PROCESS | 1675 | |0;ai-astar;116;110;116;117;116;113;115;117;116;116 05:08:24 INFO - PROCESS | 1675 | |1;audio-beat-detection;158;126;139;181;145;155;136;124;163;136 05:08:24 INFO - PROCESS | 1675 | |2;audio-dft;198;198;204;237;204;278;236;240;195;263 05:08:24 INFO - PROCESS | 1675 | |3;audio-fft;70;72;84;161;81;82;65;58;136;65 05:08:24 INFO - PROCESS | 1675 | |4;audio-oscillator;71;71;115;77;104;127;142;71;72;117 05:08:24 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;87;89;86;128;86;136;130;106;117 05:08:24 INFO - PROCESS | 1675 | |6;imaging-darkroom;151;118;96;124;101;145;116;90;154;90 05:08:24 INFO - PROCESS | 1675 | |7;imaging-desaturate;149;119;138;112;143;88;144;144;117;89 05:08:24 INFO - PROCESS | 1675 | |8;json-parse-financial;56;58;57;56;59;59;57;59;58;59 05:08:24 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;80;95;99;107;92;78;73;70;69;68 05:08:24 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;89;87;90;86;91;88;89;89;121;89 05:08:24 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;146;149;155;146;138;138;138;143;152;158 05:08:24 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;167;169;173;166;168;166;170;167;169;162 05:08:24 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;67;69;67;67;66;67;68;65;68 05:08:24 INFO - PROCESS | 1675 | __end_tp_report 05:08:24 INFO - PROCESS | 1675 | __start_cc_report 05:08:24 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1083 05:08:24 INFO - PROCESS | 1675 | __end_cc_report 05:08:24 INFO - PROCESS | 1675 | __startTimestamp1464696504270__endTimestamp 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | ------- Summary: start ------- 05:08:24 INFO - PROCESS | 1675 | Number of tests: 14 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:2.15 (1.9%) stddev-sans-first:2.26 05:08:24 INFO - PROCESS | 1675 | Values: 116.0 110.0 116.0 117.0 116.0 113.0 115.0 117.0 116.0 116.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:150.00 stddev:17.86 (11.9%) stddev-sans-first:18.44 05:08:24 INFO - PROCESS | 1675 | Values: 158.0 126.0 139.0 181.0 145.0 155.0 136.0 124.0 163.0 136.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:225.30 Median:236.50 stddev:29.78 (12.6%) stddev-sans-first:29.90 05:08:24 INFO - PROCESS | 1675 | Values: 198.0 198.0 204.0 237.0 204.0 278.0 236.0 240.0 195.0 263.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:87.40 Median:71.00 stddev:33.78 (47.6%) stddev-sans-first:35.23 05:08:24 INFO - PROCESS | 1675 | Values: 70.0 72.0 84.0 161.0 81.0 82.0 65.0 58.0 136.0 65.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:71.00 stddev:27.39 (38.6%) stddev-sans-first:27.42 05:08:24 INFO - PROCESS | 1675 | Values: 71.0 71.0 115.0 77.0 104.0 127.0 142.0 71.0 72.0 117.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:105.40 Median:86.00 stddev:20.59 (23.9%) stddev-sans-first:20.97 05:08:24 INFO - PROCESS | 1675 | Values: 89.0 87.0 89.0 86.0 128.0 86.0 136.0 130.0 106.0 117.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:118.50 Median:152.50 stddev:24.70 (16.2%) stddev-sans-first:23.23 05:08:24 INFO - PROCESS | 1675 | Values: 151.0 118.0 96.0 124.0 101.0 145.0 116.0 90.0 154.0 90.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:124.30 Median:144.00 stddev:22.90 (15.9%) stddev-sans-first:22.48 05:08:24 INFO - PROCESS | 1675 | Values: 149.0 119.0 138.0 112.0 143.0 88.0 144.0 144.0 117.0 89.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.50 stddev:1.23 (2.1%) stddev-sans-first:1.12 05:08:24 INFO - PROCESS | 1675 | Values: 56.0 58.0 57.0 56.0 59.0 59.0 57.0 59.0 58.0 59.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:83.10 Median:79.00 stddev:14.07 (17.8%) stddev-sans-first:14.88 05:08:24 INFO - PROCESS | 1675 | Values: 80.0 95.0 99.0 107.0 92.0 78.0 73.0 70.0 69.0 68.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:89.00 stddev:10.32 (11.6%) stddev-sans-first:10.89 05:08:24 INFO - PROCESS | 1675 | Values: 89.0 87.0 90.0 86.0 91.0 88.0 89.0 89.0 121.0 89.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:147.50 stddev:7.23 (4.9%) stddev-sans-first:7.66 05:08:24 INFO - PROCESS | 1675 | Values: 146.0 149.0 155.0 146.0 138.0 138.0 138.0 143.0 152.0 158.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.50 stddev:2.91 (1.7%) stddev-sans-first:3.07 05:08:24 INFO - PROCESS | 1675 | Values: 167.0 169.0 173.0 166.0 168.0 166.0 170.0 167.0 169.0 162.0 05:08:24 INFO - PROCESS | 1675 | 05:08:24 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 05:08:24 INFO - PROCESS | 1675 | Values: 67.0 67.0 69.0 67.0 67.0 66.0 67.0 68.0 65.0 68.0 05:08:24 INFO - PROCESS | 1675 | -------- Summary: end -------- 05:08:24 INFO - PROCESS | 1675 | 05:08:25 INFO - TEST-INFO | 1675: exit 0 05:08:25 INFO - TEST-OK | kraken | took 60953ms 05:08:25 INFO - SUITE-END | took 1175s 05:08:25 INFO - Completed test suite (00:19:35) 05:08:25 INFO - javascript benchmark 05:08:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7216.219310628988, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2885.4661887550806, 3458.2391873787756, 3464.844082016309, 3507.5966263879645, 3514.455259434787, 5644.266245388099, 5650.576206784487, 5736.512396446307, 5742.28221472316, 5771.711414429272, 5610.158476228567, 5715.0, 5770.740316685748, 5777.942220577793, 5914.201582786322, 4190.685698572604, 4241.4698162729655, 4891.779869905863, 5209.817656382027, 5304.70824104676, 5178.4562620924635, 5293.68237905725, 5345.412004679482, 5347.41178470368, 5372.516473517383, 5036.093503169428, 5218.739063046833, 5241.502057304588, 5273.578113750891, 5318.388385335697, 4415.580519850609, 5052.621053420971, 5071.695698258116, 5090.770915308783, 5114.977004599058, 4051.8784436466954, 4997.100521906055, 5034.622403319766, 5063.772130254148, 5076.314697515825, 5259.053370393328, 5512.476314750133, 5529.502344788988, 5588.385277619459, 5702.6293290935955, 5460.726963651841, 6162.106494558308, 6260.248770147611, 6266.185395898549, 6292.590981586182, 6246.702437956066, 6290.716917738713, 6292.244930608311, 6293.590916590407, 6306.306306306303, 5698.7435565399655, 5788.421157884178, 5801.564882633819, 5863.970680146572, 5903.645781253139, 6336.556441049082, 6338.968305158444, 6357.904631430532, 6358.586691865014, 6368.490520758328, 6137.877242455215, 6154.261488621394, 6155.138280640714, 6164.938350616437, 6165.352637972948, 6069.332373438918, 6124.350421260821, 6147.524594097385, 6163.075538669146, 6200.224971878515, 6071.089336599457, 6108.5418593605655, 6181.505479561624, 6187.659678717714, 6194.907076393858, 6241.251049874044, 6254.155688981929, 6274.090256912813, 6283.560150789493, 6289.591176573508, 4689.343491911135, 4963.70217786934, 4982.476839931827, 5021.974890125526, 5047.596192304607, 4442.400275962768, 4456.059001059285, 4456.153330867125, 4495.910081798345, 4496.7301961882395, 5788.131780232999, 5849.210356601804, 5852.063669812809, 5863.560232982543, 5868.589198756132, 6132.845047373359, 6155.0, 6194.628322300677, 6275.403836635513, 6288.213973253343, 5797.826065218051, 5806.1000544915605, 5816.476517113396, 5826.650400975955, 5849.3858144894175, 5343.225232341289, 5393.27190829233, 5420.91868621971, 5435.755391007414, 5436.401995780461, 6285.371462853678, 6287.654179020106, 6325.304216536178, 6398.072279519537, 6410.935890641033, 6278.968605157128, 6297.4017468340435, 6315.778947736807, 6319.2732835722645, 6330.8100756977365, 6211.9068213975925, 6243.43809057187, 6389.7763578274535, 6404.167458230587, 6418.711157997808, 6110.6195130195665, 6161.599496032743, 6198.132261483486, 6221.782237621662, 6231.065340199007, 5180.792768289226, 5244.606654500852, 6254.874902502015, 6269.404406581368, 6275.435210831047, 5944.4352786485215, 6061.060535616986, 6071.696415179312, 6113.877722445615, 6126.693665316628, 6020.578559500968, 6086.908696369469, 6099.634021958696, 6156.630602163885, 6250.031245157008, 5991.550633702403, 6025.698715064142, 6032.668203248863, 6032.819015429544, 6066.514678825772, 5048.949510504848, 6020.187274718032, 6055.909161362495, 6160.291566469913, 6194.783182588588, 4556.681032327838, 6049.911016017159, 6070.998285283055, 6074.967255566476, 6080.665563394055], "value": 5660.6785609026465, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.4623652691619, 510.22445882258967, 515.4536191636865, 547.1655725019347, 554.958378121641, 30423.39153216937, 42746.93132671677, 42770.786146069266, 42837.78581107098, 42889.571104289025, 41709.7914510427, 42378.0, 42408.36387454191, 42422.78788606053, 42532.0, 9584.56869440875, 9830.066143716336, 9833.459159746211, 9870.703878883609, 9872.506374681238, 9700.320977531575, 9752.756181095458, 9782.853257201148, 9822.0, 9829.4102353859, 9818.263630227726, 9841.852372214387, 9851.704448866545, 9853.014698530125, 9870.309078364517, 8395.580220988957, 9801.852972205423, 9808.90191098087, 9848.55681494331, 9854.260930430175, 9623.615055397775, 9758.804823903482, 9765.267604929659, 9767.218622510181, 9769.755756106155, 40548.18903621911, 40833.59166408327, 40933.38599921003, 41234.793826030975, 41467.585324146676, 3312.5362449257123, 3517.2086280586664, 3632.5096112024803, 3733.701303895682, 3855.8457661693496, 32081.358372832412, 32678.0, 33019.009429717145, 33229.3354132916, 33761.0, 3235.9352812943844, 3280.5243239730344, 3330.167458135466, 3342.615599206098, 3443.2941247044546, 518387.0, 528616.0, 531422.0, 532943.0, 535170.0, 70886.0, 71493.0, 71739.6413017942, 71809.40952952331, 71860.0, 37173.25653486915, 37535.36686562051, 37994.43008354877, 38981.6101838978, 39003.0, 14359.210243436712, 17636.0, 17792.555186120193, 17792.822071779374, 17801.287948481917, 13108.475660973454, 13245.470181192644, 13276.668083297995, 13278.203307801563, 13305.40125694346, 19392.90303548473, 19923.20307187725, 20113.899430502755, 21847.563048738935, 22131.336059917907, 7725.49784264021, 9101.0, 9231.399959002778, 9248.583813728399, 9254.583543740548, 894.8970868350223, 919.3472634429496, 1000.9290059636265, 1016.7966406718685, 1032.4579595712312, 31850.2037449061, 33063.83468082644, 33438.331233375684, 34129.829350853084, 34199.4870076949, 994.7267497603079, 1023.4013102335068, 1055.0715370473933, 1084.7017070305728, 1122.8820973797797, 14746.336414861356, 16455.917720411322, 16561.01267847866, 16634.6673066538, 16743.07913064793, 688540.0, 690091.0, 693573.0, 696301.0, 699200.0, 62928.685356572925, 63147.368526314145, 63643.04535431972, 63650.726985460955, 63695.36304636987, 1409.4080486195821, 1444.4511085787333, 1467.508384691119, 1476.9113853168844, 1478.0318891126115, 27487.90048398042, 30112.698873010988, 30613.23466913345, 31098.84450577823, 31973.5974915625, 55473.1679024823, 56086.87826243534, 56316.71841640765, 56346.15480767874, 56403.43596564146, 500830.0, 507033.9296606987, 512810.0, 514821.0, 519860.0, 27604.309892252855, 28037.299067523476, 28547.0, 28569.28576785597, 28584.714152859036, 49728.25407618816, 50157.74921125371, 50323.49676503188, 51369.7431512855, 51522.96954060973, 39078.023049423995, 41275.1744965105, 41509.79245103755, 41877.11195089238, 42271.78864105783, 35326.11684707903, 36554.903352899455, 40423.59576404198, 40592.0, 40964.77105686834, 39762.0, 40271.0, 41003.0, 41033.589664102976, 42561.574384255764, 33764.15589610279, 36592.90221293365, 36738.63261367459, 36850.0, 36909.26181476409, 24755.876220618782, 26399.34001649974, 26616.201513954613, 26811.525366105052, 26947.92208311724], "value": 20636.34198585828, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.5895174260056, 382.0192302909415, 384.60577907644665, 393.8660855309194, 404.2965240481563, 18923.810761892393, 19922.0, 20863.95680215989, 20865.582688346225, 21290.361289161312, 11716.882831171706, 11923.761524769521, 12110.728373520786, 12235.816462753066, 12241.693957651041, 5427.213054107162, 5596.776128954837, 5646.943530564683, 5649.887002259952, 5667.979763642543, 3275.623303320113, 5864.76540938362, 6175.969120154393, 6188.071789231606, 6251.4061164189325, 5557.360903496109, 5632.662040277596, 6228.159198508211, 6235.7505699771955, 6252.218472690914, 5864.648121112747, 5980.222571065756, 5984.192134061889, 6098.908516372258, 6104.114903339036, 5929.110633404981, 5957.076653118774, 5982.940170598282, 5986.5210783137245, 5988.461038506512, 13405.530806421728, 13629.659258518517, 19704.80295197044, 25846.87076564624, 25870.353241168934, 1962.7252184694155, 2102.5374417628095, 2137.8396620253543, 2147.044565168501, 2218.689383486313, 9853.90146098537, 10071.748206294827, 10382.22133339995, 11309.321440713584, 15902.522924312289, 1811.3388613156235, 1834.5688763140786, 1843.6036252205706, 1868.4394681595468, 1911.3405874973112, 102312.0, 117869.41065294619, 121113.78886211113, 124069.75930240762, 124382.37808810988, 11524.078073754246, 11654.766904662029, 11676.94161529187, 11727.237729547724, 11828.822567661491, 14936.775948360782, 14947.0, 14957.102573845605, 15029.398824047134, 15033.398664053315, 7205.531640443459, 8536.274416674652, 8738.432001919982, 8753.212210901049, 8813.118688131135, 6886.724531018804, 7006.9649651751415, 7425.554466732014, 8642.827143457096, 8724.345674074342, 3923.6664883484655, 4514.142312960527, 4621.861344159681, 4630.58324750774, 4678.128748500627, 1490.3293517916982, 1719.9914000429917, 1795.2729144696418, 1940.932067377635, 1972.477293517219, 840.399114633028, 927.8561822917459, 1007.2445665750687, 1020.4081632653043, 1071.796358691846, 20908.954552272324, 20920.134994341905, 21368.572628547343, 21625.918704064734, 21685.457863553223, 872.2324354568067, 911.4759013567225, 937.5687183895484, 959.7024922274118, 973.8782652168479, 7989.0, 11286.887131128584, 11430.085593152695, 11437.942810285895, 11506.252093613888, 574213.0, 584330.0, 597102.0, 598787.0, 600254.9987250036, 127328.0, 127850.72149278574, 129795.35102324617, 131369.0, 131785.68214317926, 726.7529040126383, 938.6339327662217, 941.5010044676333, 941.9811603767886, 967.2117224462041, 18728.438146855617, 18916.716249256, 19035.71446428277, 19091.0, 19313.9377334248, 34748.82625586856, 52848.67878303073, 53506.92986140333, 53590.464095360105, 54031.459685402646, 63268.36731632809, 76448.23551764633, 76487.0, 80366.0, 83094.58452707698, 7034.47241456903, 7469.663865125974, 7498.550086994798, 7643.923560764321, 7734.342580880463, 10949.781004380027, 12692.238465692088, 12844.35778211067, 13204.867951320364, 13260.933695331461, 10773.10463463894, 11035.889641103808, 11361.943190283995, 11531.481083351102, 11569.479373428048, 10604.469776511298, 10883.292585981886, 11163.162762792985, 11531.654050378502, 11585.594504192313, 11722.062235021349, 11767.705807354885, 11864.644060678194, 11973.982211511771, 12055.276683399024, 6892.896606551006, 7434.479586428898, 7605.429592780453, 8194.385421093322, 8625.611847467004, 8368.163183681583, 10587.629432970118, 11484.598039068593, 11558.190926635054, 11631.53473861036], "value": 9298.803092932887, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1578.0, 1605.8233594304625, 1828.7165489349147, 1894.564250222449, 1938.2828353509203, 8006.399520035991, 8461.53461559614, 8474.576271186446, 8966.641334346627, 8974.596143173556, 8658.480491170534, 8709.86935195971, 8739.344549158823, 8775.95612021939, 8784.429012114208, 2329.685492458522, 2351.7295511016196, 2360.634101714237, 2365.3731761083327, 2372.193454225563, 2297.5060362022164, 2302.5970455170363, 2321.9651705224437, 2334.8482348647367, 2341.0, 2336.894839732216, 2344.531093781242, 2353.576356255873, 2357.634566642165, 2358.6462030695448, 2310.514791893704, 2326.767323267668, 2328.639060945556, 2336.123953517431, 2344.3553022918663, 2331.9300420987397, 2332.8685587490054, 2333.0551126793507, 2335.2293743064747, 2338.0, 8703.086175951497, 8786.824263514758, 8842.425242359226, 8850.955745221232, 8860.202581767608, 5101.6173786965755, 5119.25770763241, 5263.947360526384, 5269.367675878881, 5315.548178404841, 3425.5033020212177, 3537.6462353764673, 3573.0531409176588, 3575.66031227033, 3645.453182022691, 835.3859912963967, 956.4022485946283, 1003.4631472162358, 1054.9683509494728, 1065.690949624608, 10922.508487118099, 11262.493187806569, 11339.433028348632, 11355.602553910656, 11542.36516991565, 4053.8175782089875, 4633.4208223972, 4706.670533062721, 4724.291356296514, 4743.359646447685, 9837.950810246046, 9879.506024698736, 9893.703188904345, 9897.703068907944, 9980.500974951223, 4442.267025940683, 5238.659487133325, 5292.973535132377, 5328.094223981932, 5342.732863356817, 4382.408374869352, 4694.554017368345, 4852.053849499384, 4863.927041094386, 4867.537583929521, 3944.881653550398, 4013.8795836124964, 4035.1929614076716, 4080.6939479539155, 4135.110951145518, 1170.9180357375071, 1176.6293617510457, 1179.292424545266, 1182.3083496154675, 1245.2404033539533, 751.2637615137196, 815.6003558256411, 889.6841621224502, 912.6851236323457, 919.6229545886155, 4529.547045295444, 5607.607467477319, 5827.737751801179, 5897.32180799205, 6013.759449621966, 907.5825120444538, 916.3539704508306, 969.8157350103598, 970.1511177719495, 991.5141580625442, 3824.0631045394057, 3899.66852817508, 3963.1875465529497, 4062.837486500566, 4098.7335823172, 10431.426271554985, 10734.78530429387, 10771.78456430867, 10777.83833242502, 10812.35125892449, 9021.368504204775, 10296.330738501973, 10813.945930270298, 10823.29648572856, 10870.347779133277, 7322.901564765328, 8010.359171266285, 8149.185081491803, 8170.509769413854, 8359.038710548364, 5788.739506722123, 5895.174675545341, 5909.113632955091, 5925.140854576149, 5937.465628093492, 9740.366876153028, 10117.190624749855, 10159.187265018632, 10163.542640581043, 10189.74525636865, 9956.054174853378, 10239.948800255952, 10360.222983276133, 10383.532741026824, 10410.271281010257, 6096.695165241809, 6574.605523668595, 7041.155061392461, 7149.249328820607, 7222.169450513048, 8463.873041904253, 8468.0, 8469.74590762278, 8567.186117318844, 8567.74296771098, 7426.517276377018, 7586.772396828104, 7611.467197296136, 7629.3133617974645, 7712.421568382281, 7287.27127287267, 7340.853182936418, 7341.74303899361, 7396.0754905636795, 7527.209642987407, 7076.0801095857205, 7111.502194846311, 7129.964350178216, 7133.8929916050265, 7159.463040271896, 4466.7939656293265, 4835.1296766580945, 4907.0, 5000.824971125993, 5014.172661510956, 4946.406431228275, 4970.453250142409, 5025.698458092526, 5138.177891537336, 5143.125668636266], "value": 4550.723443094427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.5416739472306, 309.9085769697939, 316.12906442750227, 319.8784461904476, 321.1489552685383, 4966.577840883525, 5388.548210564194, 5457.918131228034, 5577.525910297624, 5633.295838020247, 5023.572996295319, 5376.499927533696, 5499.587530935186, 5540.091451987699, 5561.63849349792, 3932.9970857431317, 4166.354215096665, 4170.68719846012, 4192.350185721218, 4204.579542045802, 4006.5793091725386, 4008.3586626139822, 4157.6072015874715, 4173.227952828721, 4212.630894959135, 3683.686886614641, 3786.924261514768, 4041.3533834586324, 4106.404571337154, 4109.856155034575, 3469.9479507807278, 3648.154439537165, 3973.7019723520707, 3992.820323085439, 4151.8962025949295, 3043.786934914535, 3057.541368794676, 3329.816860072695, 3577.0520811984843, 3603.648631763089, 4350.282203436429, 5327.573794096502, 5352.785888564453, 5413.863088751367, 5423.484768946943, 4180.958190418118, 4730.62155027597, 4741.905161896777, 4770.570648641604, 4771.308160316769, 5474.069408200616, 5823.767808866078, 5883.6465413834385, 5897.32180799205, 6029.758809647609, 3034.8179109253515, 3155.7475401968018, 3943.605639436062, 3952.3281042222893, 3954.169624378884, 4973.875653108665, 5215.269862219292, 6206.565540412173, 6224.564280500367, 6241.781537646206, 3085.568020477135, 3539.876104336336, 3946.9802650987135, 3983.721139520264, 4005.4192142139227, 3405.6083550391527, 3429.868143512635, 4040.5151381834366, 4048.4939382577177, 4070.898227544335, 4282.122164956177, 4525.479569849444, 4653.464851542049, 4687.929681054787, 4723.968693461776, 4959.876003099951, 5597.80407685729, 5902.291724993029, 5904.350521442638, 5954.166416701665, 1332.6734949937133, 1378.7931810228547, 1384.5292600515672, 1387.1607677355257, 1397.329281944652, 725.9310365515267, 735.8638651849425, 740.3336996702902, 820.6052087148386, 832.076395201326, 4293.273771155276, 4335.436393268855, 4450.3991961085485, 4484.730916145042, 4714.905701885943, 4220.775974967225, 4259.574042595716, 5039.689680682975, 5156.9887743779955, 5182.585393168538, 3965.4138344661787, 4069.4099355593867, 4536.119564076769, 4592.265237561974, 4636.35091087248, 2436.0012394918, 2460.175841093218, 2673.791450259696, 2757.8279231326605, 2759.4205216904274, 4624.653151013687, 4646.582792248397, 4720.0, 4808.879778005507, 4866.5376789205675, 4479.664025198123, 4568.657350698711, 4569.240842275682, 4818.445878723992, 5219.6689844089515, 4049.959500405017, 4533.775880512243, 4604.217283061955, 4870.683405578626, 5335.973320133375, 4472.731636101845, 4588.357629931746, 5059.507445303718, 5159.9102363542825, 5167.0, 4232.153569286216, 4705.411823522059, 4850.927236091391, 5015.949840501694, 5110.48895110486, 4404.4494438195225, 4725.362076119817, 4748.180596694427, 4934.802607895787, 5208.479152084761, 3200.2959348943195, 3321.5017747338093, 3403.676650718178, 3501.7373696972318, 3505.0021487751737, 4496.459500382273, 4498.910021799611, 4690.648201384978, 5066.695998240118, 5136.691798492103, 4931.816364072525, 5027.622928280321, 5814.3604203538425, 5848.64908105515, 5886.38192989747, 4817.7350245736825, 4955.008998200302, 5698.922581805944, 5705.600607957404, 5814.825555233349, 4862.270659401118, 5443.618946673853, 5728.684922329312, 5730.424133363358, 5784.2191304175085, 5259.47405259471, 5838.795642152504, 5844.970775146097, 5886.440788125121, 5895.41045895407, 5083.440821509557, 5668.574856885668, 5702.0, 5745.74144163522, 5748.252727145445], "value": 3913.845180563541, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.457866417534, 775.1550809617517, 782.4092809928507, 853.4495250563383, 863.8272345530894, 14672.486462973799, 15033.173175475344, 15228.933974621781, 15233.695326093486, 15573.29920153593, 15927.601809954756, 15985.0, 15997.280122394492, 15998.360065597362, 16013.11927843968, 698.6227437183915, 857.8327226190895, 894.575076838503, 895.0288936503902, 903.3044555692113, 882.9072947340533, 887.9911200888006, 888.0542222533014, 890.2566357091836, 890.5057692980394, 935.5041827831226, 944.3295260365114, 944.5418971798706, 945.4469135555709, 946.1390134977172, 908.14180599333, 928.4846909964966, 935.5369092299355, 936.6159874451477, 938.8497840345547, 674.7806962737081, 681.7034589953386, 696.5646470955653, 697.9616121113337, 698.6087790837097, 14847.554573362817, 14931.10413375201, 14934.551963441114, 14952.47666331684, 14975.0, 13689.178649280975, 13696.315184240848, 13701.177929324273, 13781.242031688253, 13827.170369777845, 11605.24565903222, 11767.588134415255, 11956.983656389217, 11969.186660780484, 12219.694507637292, 3722.813859307024, 3745.232227393378, 3782.224643947984, 3828.272628200633, 3927.8428862845453, 89744.55127724385, 90702.0, 90714.54642726744, 91538.62692059625, 91604.54197729036, 10454.52954617044, 10659.307145035547, 10734.946325268324, 10739.946300268604, 10926.836097458545, 20040.89979550093, 20404.897975510026, 20544.691829622567, 20884.060217290258, 21382.679259811117, 6877.6217308048535, 7079.89380159298, 7113.0, 7987.68049278034, 8034.718784842503, 3795.373763329075, 4148.668106551469, 4555.2256116460285, 4578.793954272066, 4589.426321709786, 3284.981655686745, 3307.3881331953667, 3369.309291595217, 3370.8651653934057, 3376.037829218661, 551.4457969740386, 630.3979699387074, 631.5831551176202, 640.69887153038, 641.0704478506185, 583.3408248678966, 607.2379164149053, 623.6975067092457, 699.989500157498, 701.5002381330394, 7619.390448764086, 8523.0, 8868.802711633976, 9503.619855205714, 9532.168700535007, 594.878049999749, 609.871926895357, 656.5830697507117, 677.8237658208901, 679.2867489136387, 4299.849505267301, 4475.239209334421, 4497.302918047708, 4501.617362524222, 4506.6406617395805, 92023.0, 92727.0, 93077.5346123265, 93674.0, 93952.0, 64325.67837160878, 80584.59707701541, 81809.0, 83135.0, 84123.0, 66818.0, 67797.66101169462, 67991.66004169948, 73918.63040684762, 74930.62534687291, 7676.692932282646, 9215.545303856445, 9292.6282948684, 9304.0, 9397.624095036123, 42242.78878605587, 44093.33859992038, 44138.0, 45652.771736141105, 46127.308090378, 61144.0, 61453.0, 64698.02952955615, 67158.66420668061, 67732.661336693, 12311.692207694521, 12395.566155184886, 12624.305663188612, 12974.091813572959, 13112.344382780631, 14060.156390616596, 14361.425542978164, 14405.06367086136, 14412.42350306018, 14674.63313417173, 13507.122037067558, 13571.796423053465, 13620.523281685093, 13793.793093103812, 13842.515511957034, 13263.734725305632, 13451.977736842884, 13583.592492225249, 13763.174209547458, 13881.097728647606, 10838.783224335626, 12823.422945967268, 12935.482580697044, 12973.416196271379, 13528.86471135276, 9853.76827896513, 11020.338779673246, 11182.32906025641, 11193.832092518456, 11385.658430247106, 9047.909520904706, 11093.223474356717, 11285.37930413835, 11304.886951130384, 11333.320000799979], "value": 7297.880157037532, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1633.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 116.0, 117.0, 116.0, 113.0, 115.0, 117.0, 116.0, 116.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 139.0, 181.0, 145.0, 155.0, 136.0, 124.0, 163.0, 136.0], "value": 146.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 204.0, 237.0, 204.0, 278.0, 236.0, 240.0, 195.0, 263.0], "value": 225.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 84.0, 161.0, 81.0, 82.0, 65.0, 58.0, 136.0, 65.0], "value": 87.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 115.0, 77.0, 104.0, 127.0, 142.0, 71.0, 72.0, 117.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 128.0, 86.0, 136.0, 130.0, 106.0, 117.0], "value": 105.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 96.0, 124.0, 101.0, 145.0, 116.0, 90.0, 154.0, 90.0], "value": 118.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 138.0, 112.0, 143.0, 88.0, 144.0, 144.0, 117.0, 89.0], "value": 124.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 56.0, 59.0, 59.0, 57.0, 59.0, 58.0, 59.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 95.0, 99.0, 107.0, 92.0, 78.0, 73.0, 70.0, 69.0, 68.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 86.0, 91.0, 88.0, 89.0, 89.0, 121.0, 89.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 149.0, 155.0, 146.0, 138.0, 138.0, 138.0, 143.0, 152.0, 158.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 173.0, 166.0, 168.0, 166.0, 170.0, 167.0, 169.0, 162.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 67.0, 66.0, 67.0, 68.0, 65.0, 68.0], "value": 67.1, "unit": "score"}]}]} 05:08:25 INFO - Return code: 0 05:08:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:08:25 ERROR - # TBPL SUCCESS # 05:08:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:25 INFO - 2016-05-31 05:08:25.601 screenresolution[1680:10549] starting screenresolution argv=screenresolution get 05:08:25 INFO - 2016-05-31 05:08:25.604 screenresolution[1680:10549] Display 0: 1600x1200x32@60 05:08:25 INFO - 2016-05-31 05:08:25.610 screenresolution[1681:10552] starting screenresolution argv=screenresolution list 05:08:25 INFO - Available Modes on Display 0 05:08:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:25 INFO - Intel Iris: 05:08:25 INFO - Chipset Model: Intel Iris 05:08:25 INFO - Type: GPU 05:08:25 INFO - Bus: Built-In 05:08:25 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:25 INFO - Vendor: Intel (0x8086) 05:08:25 INFO - Device ID: 0x0a2e 05:08:25 INFO - Revision ID: 0x0009 05:08:25 INFO - Displays: 05:08:25 INFO - Display: 05:08:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:25 INFO - Display Serial Number: 1600x1200 60 05:08:25 INFO - Main Display: Yes 05:08:25 INFO - Mirror: Off 05:08:25 INFO - Online: Yes 05:08:25 INFO - Rotation: Supported 05:08:25 INFO - Return code: 0 05:08:25 INFO - Running post-action listener: _resource_record_post_action 05:08:25 INFO - ##### 05:08:25 INFO - ##### Finished run-tests step (success) 05:08:25 INFO - ##### 05:08:25 INFO - Running post-run listener: _resource_record_post_run 05:08:25 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 31448576; Write bytes: 698547712; Read time: 195; Write time: 1964 05:08:25 INFO - TinderboxPrint: CPU usage
54.5% 05:08:25 INFO - TinderboxPrint: I/O read bytes / time
31,448,576 / 195 05:08:25 INFO - TinderboxPrint: I/O write bytes / time
698,547,712 / 1,964 05:08:25 INFO - TinderboxPrint: CPU idle
2,204.0 (45.5%) 05:08:25 INFO - TinderboxPrint: CPU system
975.9 (20.1%) 05:08:25 INFO - TinderboxPrint: CPU user
1,665.7 (34.4%) 05:08:25 INFO - TinderboxPrint: Swap in / out
426,827,776 / 0 05:08:25 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 382663168; Write bytes: 377132544; Read time: 28875; Write time: 798 05:08:25 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 30526976; Write bytes: 296310784; Read time: 184; Write time: 1107 05:08:26 INFO - Running post-run listener: _upload_blobber_files 05:08:26 INFO - Blob upload gear active. 05:08:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:08:26 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:26 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:26 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:08:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:27 INFO - (blobuploader) - INFO - Done attempting. 05:08:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:08:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:08:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:28 INFO - (blobuploader) - INFO - Done attempting. 05:08:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:08:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:08:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:31 INFO - (blobuploader) - INFO - Done attempting. 05:08:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:31 INFO - Return code: 0 05:08:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc45d8ab0133b23dfea8a9c7bbed911f6bf2cc23e4b40dee11f5cea74b83e6a1e85aea8805e84065a54ef54c08beaa220886a3aa8e5de5d11783bdbff48faf3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd7285ea38998382f2ca08edeffa85155e7eb2ee39da7a73d533707fe3bdf19545d7fe71661574c6eeb56263d0c8c41889f94c1b1b3d33389f5b49ede7af3374", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31ad49130df620c5cb92f3029864ee3f4ab1d45256bf3eff78dd1283351eedb6a02f4845a58e8851939adbbfe2f774e909f888bfb82c0bc551198c83aad84eed"} 05:08:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:31 INFO - Contents: 05:08:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc45d8ab0133b23dfea8a9c7bbed911f6bf2cc23e4b40dee11f5cea74b83e6a1e85aea8805e84065a54ef54c08beaa220886a3aa8e5de5d11783bdbff48faf3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd7285ea38998382f2ca08edeffa85155e7eb2ee39da7a73d533707fe3bdf19545d7fe71661574c6eeb56263d0c8c41889f94c1b1b3d33389f5b49ede7af3374", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31ad49130df620c5cb92f3029864ee3f4ab1d45256bf3eff78dd1283351eedb6a02f4845a58e8851939adbbfe2f774e909f888bfb82c0bc551198c83aad84eed"} 05:08:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:31 INFO - Copying logs to upload dir... 05:08:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.193448 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-05-31 05:08:31.620856) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 05:08:31.624191) ========= 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.TlCjxspFgs/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.Tfln5NEncm/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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/Firefox/sha512/ffc45d8ab0133b23dfea8a9c7bbed911f6bf2cc23e4b40dee11f5cea74b83e6a1e85aea8805e84065a54ef54c08beaa220886a3aa8e5de5d11783bdbff48faf3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd7285ea38998382f2ca08edeffa85155e7eb2ee39da7a73d533707fe3bdf19545d7fe71661574c6eeb56263d0c8c41889f94c1b1b3d33389f5b49ede7af3374", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31ad49130df620c5cb92f3029864ee3f4ab1d45256bf3eff78dd1283351eedb6a02f4845a58e8851939adbbfe2f774e909f888bfb82c0bc551198c83aad84eed"} build_url:https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/cjKVdTn4SdesIBBPx6_5bw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc45d8ab0133b23dfea8a9c7bbed911f6bf2cc23e4b40dee11f5cea74b83e6a1e85aea8805e84065a54ef54c08beaa220886a3aa8e5de5d11783bdbff48faf3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd7285ea38998382f2ca08edeffa85155e7eb2ee39da7a73d533707fe3bdf19545d7fe71661574c6eeb56263d0c8c41889f94c1b1b3d33389f5b49ede7af3374", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31ad49130df620c5cb92f3029864ee3f4ab1d45256bf3eff78dd1283351eedb6a02f4845a58e8851939adbbfe2f774e909f888bfb82c0bc551198c83aad84eed"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 05:08:31.655747) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:08:31.656096) ========= 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.TlCjxspFgs/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.Tfln5NEncm/Listeners TMPDIR=/var/folders/cl/xc50dtmn0c7ds5688s724z3w00000w/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.005190 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 05:08:31.704540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 05:08:31.704860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 05:08:31.705213) ========= ========= Total master_lag: 0.18 =========