builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0113 starttime: 1463189847.24 results: success (0) buildid: 20160513164442 builduid: 8e9f0e099d6441e89ab9e1b18f4eb5f6 revision: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.235912) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.236342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.236633) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.256219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.256506) ========= 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.oBSGYuErr6/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.ctKzSeOH2I/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-13 18:37:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-05-13 18:37:27 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147870 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.421459) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.423091) ========= 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.oBSGYuErr6/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.ctKzSeOH2I/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.020117 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:27.472738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 18:37:27.473072) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2afd8fa9bb5df5577e5566468bb423b76c63cc77 --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 2afd8fa9bb5df5577e5566468bb423b76c63cc77 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oBSGYuErr6/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.ctKzSeOH2I/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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-13 18:37:27,552 truncating revision to first 12 chars 2016-05-13 18:37:27,553 Setting DEBUG logging. 2016-05-13 18:37:27,553 attempt 1/10 2016-05-13 18:37:27,553 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2afd8fa9bb5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 18:37:28,778 unpacking tar archive at: mozilla-central-2afd8fa9bb5d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.477176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 18:37:28.969829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:28.970150) ========= script_repo_revision: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:28.970526) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:28.970800) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:37:28.983548) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-13 18:37:28.983824) ========= /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.oBSGYuErr6/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.ctKzSeOH2I/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:37:29 INFO - MultiFileLogger online at 20160513 18:37:29 in /builds/slave/test 18:37:29 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 18:37:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:37:29 INFO - {'append_to_log': False, 18:37:29 INFO - 'base_work_dir': '/builds/slave/test', 18:37:29 INFO - 'blob_upload_branch': 'Firefox', 18:37:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:37:29 INFO - 'branch': 'Firefox', 18:37:29 INFO - 'buildbot_json_path': 'buildprops.json', 18:37:29 INFO - 'config_files': ('talos/mac_config.py',), 18:37:29 INFO - 'default_actions': ('clobber', 18:37:29 INFO - 'read-buildbot-config', 18:37:29 INFO - 'download-and-extract', 18:37:29 INFO - 'populate-webroot', 18:37:29 INFO - 'create-virtualenv', 18:37:29 INFO - 'install', 18:37:29 INFO - 'run-tests'), 18:37:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:37:29 INFO - 'download_minidump_stackwalk': True, 18:37:29 INFO - 'download_symbols': 'ondemand', 18:37:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:37:29 INFO - 'tooltool.py': '/tools/tooltool.py', 18:37:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:37:29 INFO - '/tools/misc-python/virtualenv.py')}, 18:37:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:37:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:37:29 INFO - 'installer_path': 'installer.exe', 18:37:29 INFO - 'log_level': 'info', 18:37:29 INFO - 'log_name': 'talos', 18:37:29 INFO - 'log_to_console': True, 18:37:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:37:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:37:29 INFO - 'opt_config_files': (), 18:37:29 INFO - 'pip_index': False, 18:37:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:37:29 INFO - 'cmd': ('bash', 18:37:29 INFO - '-c', 18:37:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:37:29 INFO - 'enabled': True, 18:37:29 INFO - 'halt_on_failure': False, 18:37:29 INFO - 'name': 'check_screen_resolution'},), 18:37:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:37:29 INFO - 'cmd': ('bash', 18:37:29 INFO - '-c', 18:37:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:37:29 INFO - 'enabled': True, 18:37:29 INFO - 'halt_on_failure': False, 18:37:29 INFO - 'name': 'check_screen_resolution'},), 18:37:29 INFO - 'run_cmd_checks_enabled': True, 18:37:29 INFO - 'sps_profile': False, 18:37:29 INFO - 'sps_profile_interval': 0, 18:37:29 INFO - 'suite': 'dromaeojs-e10s', 18:37:29 INFO - 'system_bits': '32', 18:37:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:37:29 INFO - 'title': 't-yosemite-r7-0113', 18:37:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:37:29 INFO - 'use_talos_json': True, 18:37:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:37:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:37:29 INFO - 'work_dir': 'build'} 18:37:29 INFO - ##### 18:37:29 INFO - ##### Running clobber step. 18:37:29 INFO - ##### 18:37:29 INFO - Running pre-action listener: _resource_record_pre_action 18:37:29 INFO - Running main action method: clobber 18:37:29 INFO - rmtree: /builds/slave/test/build 18:37:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:37:30 INFO - Running post-action listener: _resource_record_post_action 18:37:30 INFO - ##### 18:37:30 INFO - ##### Finished clobber step (success) 18:37:30 INFO - ##### 18:37:30 INFO - ##### 18:37:30 INFO - ##### Running read-buildbot-config step. 18:37:30 INFO - ##### 18:37:30 INFO - Running pre-action listener: _resource_record_pre_action 18:37:30 INFO - Running main action method: read_buildbot_config 18:37:30 INFO - Using buildbot properties: 18:37:30 INFO - { 18:37:30 INFO - "project": "", 18:37:30 INFO - "product": "firefox", 18:37:30 INFO - "script_repo_revision": "production", 18:37:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:37:30 INFO - "repository": "", 18:37:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:37:30 INFO - "buildid": "20160513164442", 18:37:30 INFO - "pgo_build": "False", 18:37:30 INFO - "basedir": "/builds/slave/test", 18:37:30 INFO - "buildnumber": 12, 18:37:30 INFO - "slavename": "t-yosemite-r7-0113", 18:37:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:37:30 INFO - "platform": "yosemite_r7", 18:37:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:37:30 INFO - "branch": "mozilla-central", 18:37:30 INFO - "repo_path": "mozilla-central", 18:37:30 INFO - "revision": "2afd8fa9bb5df5577e5566468bb423b76c63cc77", 18:37:30 INFO - "stage_platform": "macosx64", 18:37:30 INFO - "builduid": "8e9f0e099d6441e89ab9e1b18f4eb5f6", 18:37:30 INFO - "slavebuilddir": "test" 18:37:30 INFO - } 18:37:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:37:30 INFO - Running post-action listener: _resource_record_post_action 18:37:30 INFO - ##### 18:37:30 INFO - ##### Finished read-buildbot-config step (success) 18:37:30 INFO - ##### 18:37:30 INFO - ##### 18:37:30 INFO - ##### Running download-and-extract step. 18:37:30 INFO - ##### 18:37:30 INFO - Running pre-action listener: _resource_record_pre_action 18:37:30 INFO - Running main action method: download_and_extract 18:37:30 INFO - mkdir: /builds/slave/test/build/tests 18:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:30 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:37:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:37:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:37:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:31 INFO - Downloaded 1199 bytes. 18:37:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:37:31 INFO - Using the following test package requirements: 18:37:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:37:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:37:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:37:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'jsshell-mac.zip'], 18:37:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:37:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:37:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:37:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:37:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:37:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:37:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:37:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:37: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')]} 18:37:31 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:37:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:33 INFO - Downloaded 18266856 bytes. 18:37:33 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'] 18:37:33 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 18:37:36 INFO - Return code: 0 18:37:36 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 18:37:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:36 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:37:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:37:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:39 INFO - Downloaded 18266856 bytes. 18:37:39 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'] 18:37:39 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 18:37:43 INFO - Return code: 0 18:37: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')]} 18:37:43 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:37:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:37:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:37:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:45 INFO - Downloaded 10840194 bytes. 18:37:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:37:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:37:45 INFO - Return code: 0 18:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:45 INFO - https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:37:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:37:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:37:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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 18:37:49 INFO - Downloaded 110020047 bytes. 18:37:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:37:49 INFO - mkdir: /builds/slave/test/properties 18:37:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:37:49 INFO - Writing to file /builds/slave/test/properties/build_url 18:37:49 INFO - Contents: 18:37:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:37:50 INFO - Running post-action listener: _resource_record_post_action 18:37:50 INFO - Running post-action listener: set_extra_try_arguments 18:37:50 INFO - ##### 18:37:50 INFO - ##### Finished download-and-extract step (success) 18:37:50 INFO - ##### 18:37:50 INFO - ##### 18:37:50 INFO - ##### Running populate-webroot step. 18:37:50 INFO - ##### 18:37:50 INFO - Running pre-action listener: _resource_record_pre_action 18:37:50 INFO - Running main action method: populate_webroot 18:37:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:37:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:37:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:37:50 INFO - '--tppagecycles', 18:37:50 INFO - '7'], 18:37:50 INFO - 'tests': ['tsvgm']}}, 18:37:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:37:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:37:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:37:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:37:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:37:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:37:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['damp', 'tps']}, 18:37:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'tests': ['damp', 'tps']}, 18:37:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['dromaeo_dom']}, 18:37:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:37:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore', 18:37:50 INFO - 'tabpaint']}, 18:37:50 INFO - 'other-e10s': {'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore', 18:37:50 INFO - 'tabpaint']}, 18:37:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore', 18:37:50 INFO - 'tabpaint']}, 18:37:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore', 18:37:50 INFO - 'tabpaint']}, 18:37:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore', 18:37:50 INFO - 'tabpaint']}, 18:37:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['a11yr', 18:37:50 INFO - 'ts_paint', 18:37:50 INFO - 'tpaint', 18:37:50 INFO - 'sessionrestore', 18:37:50 INFO - 'sessionrestore_no_auto_restore', 18:37:50 INFO - 'tabpaint']}, 18:37:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['tsvgx', 18:37:50 INFO - 'tsvgr_opacity', 18:37:50 INFO - 'tart', 18:37:50 INFO - 'tscrollx', 18:37:50 INFO - 'cart']}, 18:37:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:37:50 INFO - 'tsvgr_opacity', 18:37:50 INFO - 'tart', 18:37:50 INFO - 'tscrollx', 18:37:50 INFO - 'cart']}, 18:37:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--disable-e10s'], 18:37:50 INFO - 'tests': ['tp5o']}, 18:37:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'tests': ['tp5o']}, 18:37:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--disable-e10s', 18:37:50 INFO - '--xperf_path', 18:37:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:37:50 INFO - 'tests': ['tp5n']}, 18:37:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:37:50 INFO - 'talos_options': ['--xperf_path', 18:37:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:37:50 INFO - 'tests': ['tp5n']}}, 18:37:50 INFO - 'talos.zip': {'path': '', 18:37:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:37:50 INFO - Running post-action listener: _resource_record_post_action 18:37:50 INFO - ##### 18:37:50 INFO - ##### Finished populate-webroot step (success) 18:37:50 INFO - ##### 18:37:50 INFO - ##### 18:37:50 INFO - ##### Running create-virtualenv step. 18:37:50 INFO - ##### 18:37:50 INFO - Running pre-action listener: _resource_record_pre_action 18:37:50 INFO - Running main action method: create_virtualenv 18:37:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:37:50 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 18:37:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:37:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:37:50 INFO - Using real prefix '/tools/python27' 18:37:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:37:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:37:54 INFO - Installing pip.................done. 18:37:54 INFO - Return code: 0 18:37:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:37:54 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')]} 18:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:54 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')]} 18:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:54 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:37:54 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 18:37:54 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 18:37:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:37:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:54 INFO - 'HOME': '/Users/cltbld', 18:37:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:54 INFO - 'LOGNAME': 'cltbld', 18:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:54 INFO - 'MOZ_NO_REMOTE': '1', 18:37:54 INFO - 'NO_EM_RESTART': '1', 18:37:54 INFO - 'PAGER': '/bin/cat', 18:37:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:54 INFO - 'PWD': '/builds/slave/test', 18:37:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:54 INFO - 'SHELL': '/bin/bash', 18:37:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:37:54 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:37:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:54 INFO - 'USER': 'cltbld', 18:37:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:54 INFO - 'XPC_FLAGS': '0x0', 18:37:54 INFO - 'XPC_SERVICE_NAME': '0', 18:37:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:54 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 18:37:54 INFO - Cleaning up... 18:37:54 INFO - Return code: 0 18:37:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:37:54 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')]} 18:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:54 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')]} 18:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:54 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:37:54 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 18:37:54 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 18:37:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:37:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:54 INFO - 'HOME': '/Users/cltbld', 18:37:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:54 INFO - 'LOGNAME': 'cltbld', 18:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:54 INFO - 'MOZ_NO_REMOTE': '1', 18:37:54 INFO - 'NO_EM_RESTART': '1', 18:37:54 INFO - 'PAGER': '/bin/cat', 18:37:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:54 INFO - 'PWD': '/builds/slave/test', 18:37:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:54 INFO - 'SHELL': '/bin/bash', 18:37:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:37:54 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:37:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:54 INFO - 'USER': 'cltbld', 18:37:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:54 INFO - 'XPC_FLAGS': '0x0', 18:37:54 INFO - 'XPC_SERVICE_NAME': '0', 18:37:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:54 INFO - Downloading/unpacking psutil>=3.1.1 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:37:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:37:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:37:58 INFO - Installing collected packages: psutil 18:37:58 INFO - Running setup.py install for psutil 18:37:58 INFO - building 'psutil._psutil_osx' extension 18:37:58 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 18:37:58 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 18:37:58 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 18:37:58 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 18:37:58 INFO - building 'psutil._psutil_posix' extension 18:37:58 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 18:37:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:37:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:37:58 INFO - ^ 18:37:58 INFO - 1 warning generated. 18:37:58 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 18:37:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:37:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:37:59 INFO - Successfully installed psutil 18:37:59 INFO - Cleaning up... 18:37:59 INFO - Return code: 0 18:37:59 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 18:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 18:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 18:37:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:37:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:59 INFO - 'HOME': '/Users/cltbld', 18:37:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:59 INFO - 'LOGNAME': 'cltbld', 18:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:59 INFO - 'MOZ_NO_REMOTE': '1', 18:37:59 INFO - 'NO_EM_RESTART': '1', 18:37:59 INFO - 'PAGER': '/bin/cat', 18:37:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:59 INFO - 'PWD': '/builds/slave/test', 18:37:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:59 INFO - 'SHELL': '/bin/bash', 18:37:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:37:59 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:37:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:59 INFO - 'USER': 'cltbld', 18:37:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:59 INFO - 'XPC_FLAGS': '0x0', 18:37:59 INFO - 'XPC_SERVICE_NAME': '0', 18:37:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:59 INFO - Downloading/unpacking mozsystemmonitor==0.1 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:37:59 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 18:38:01 INFO - Downloading mozsystemmonitor-0.1.tar.gz 18:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:38:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 18:38:02 INFO - Installing collected packages: mozsystemmonitor 18:38:02 INFO - Running setup.py install for mozsystemmonitor 18:38:02 INFO - Successfully installed mozsystemmonitor 18:38:02 INFO - Cleaning up... 18:38:02 INFO - Return code: 0 18:38:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:02 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:38:02 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 18:38:02 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 18:38:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:38:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:02 INFO - 'HOME': '/Users/cltbld', 18:38:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:02 INFO - 'LOGNAME': 'cltbld', 18:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:02 INFO - 'MOZ_NO_REMOTE': '1', 18:38:02 INFO - 'NO_EM_RESTART': '1', 18:38:02 INFO - 'PAGER': '/bin/cat', 18:38:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:02 INFO - 'PWD': '/builds/slave/test', 18:38:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:02 INFO - 'SHELL': '/bin/bash', 18:38:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:38:02 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:38:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:02 INFO - 'USER': 'cltbld', 18:38:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:02 INFO - 'XPC_FLAGS': '0x0', 18:38:02 INFO - 'XPC_SERVICE_NAME': '0', 18:38:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:38:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:38:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:06 INFO - Downloading docopt-0.6.1.tar.gz 18:38:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:38:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:38:06 INFO - Running setup.py install for blobuploader 18:38:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:38:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:38:06 INFO - Running setup.py install for requests 18:38:07 INFO - Running setup.py install for docopt 18:38:07 INFO - Successfully installed blobuploader requests docopt 18:38:07 INFO - Cleaning up... 18:38:07 INFO - Return code: 0 18:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:07 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:38:07 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 18:38:07 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 18:38:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:38:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:07 INFO - 'HOME': '/Users/cltbld', 18:38:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:07 INFO - 'LOGNAME': 'cltbld', 18:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:07 INFO - 'MOZ_NO_REMOTE': '1', 18:38:07 INFO - 'NO_EM_RESTART': '1', 18:38:07 INFO - 'PAGER': '/bin/cat', 18:38:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:07 INFO - 'PWD': '/builds/slave/test', 18:38:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:07 INFO - 'SHELL': '/bin/bash', 18:38:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:38:07 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:38:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:07 INFO - 'USER': 'cltbld', 18:38:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:07 INFO - 'XPC_FLAGS': '0x0', 18:38:07 INFO - 'XPC_SERVICE_NAME': '0', 18:38:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:07 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-jrvS8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:07 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-nItCLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:07 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-GwLD4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:07 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-JmqCxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:07 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Iv0Tnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-_9b9e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-yV8Cq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-t8Z3PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-9PD1e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ZpZuZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-kqmuq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-RT4egO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-pz5f7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:38:08 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-N4QGx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:09 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-qzAbDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:38:09 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-lCJ7Gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:38:09 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-3hASS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:38:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:38:09 INFO - Running setup.py install for manifestparser 18:38:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:38:09 INFO - Running setup.py install for mozcrash 18:38:09 INFO - Running setup.py install for mozdebug 18:38:09 INFO - Running setup.py install for mozdevice 18:38:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:38:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:38:09 INFO - Running setup.py install for mozfile 18:38:10 INFO - Running setup.py install for mozhttpd 18:38:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Running setup.py install for mozinfo 18:38:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Running setup.py install for mozInstall 18:38:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Running setup.py install for mozleak 18:38:10 INFO - Running setup.py install for mozlog 18:38:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Running setup.py install for moznetwork 18:38:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:38:10 INFO - Running setup.py install for mozprocess 18:38:11 INFO - Running setup.py install for mozprofile 18:38:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:38:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:38:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:38:11 INFO - Running setup.py install for mozrunner 18:38:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:38:11 INFO - Running setup.py install for mozscreenshot 18:38:11 INFO - Running setup.py install for moztest 18:38:11 INFO - Running setup.py install for mozversion 18:38:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:38:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:38:11 INFO - Cleaning up... 18:38:11 INFO - Return code: 0 18:38:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:11 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')]} 18:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:11 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')]} 18:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:11 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:38:11 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 18:38:11 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 18:38:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:38:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:11 INFO - 'HOME': '/Users/cltbld', 18:38:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:11 INFO - 'LOGNAME': 'cltbld', 18:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:11 INFO - 'MOZ_NO_REMOTE': '1', 18:38:11 INFO - 'NO_EM_RESTART': '1', 18:38:11 INFO - 'PAGER': '/bin/cat', 18:38:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:11 INFO - 'PWD': '/builds/slave/test', 18:38:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:11 INFO - 'SHELL': '/bin/bash', 18:38:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:38:11 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:38:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:11 INFO - 'USER': 'cltbld', 18:38:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:11 INFO - 'XPC_FLAGS': '0x0', 18:38:11 INFO - 'XPC_SERVICE_NAME': '0', 18:38:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-TlMWZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-kx1nQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-ZMuQQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-Eh_ls5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-mH82s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-g1Kpr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-DFZ_kZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:12 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)) 18:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:12 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-7Q4pgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-q_XHR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-wq7uUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-4bjJ_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-eNwPCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-uuq82V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-7MbFLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-teqjmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-H_x9u8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:38:13 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)) 18:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:38:13 INFO - Running setup.py (path:/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/pip-3tuFXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:38:13 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)) 18:38:13 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)) 18:38:13 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)) 18:38:13 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)) 18:38:13 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)) 18:38:13 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)) 18:38:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:16 INFO - Downloading blessings-1.6.tar.gz 18:38:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:38:16 INFO - Installing collected packages: blessings 18:38:16 INFO - Running setup.py install for blessings 18:38:16 INFO - Successfully installed blessings 18:38:16 INFO - Cleaning up... 18:38:16 INFO - Return code: 0 18:38:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:38:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:38:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:38:17 INFO - Reading from file tmpfile_stdout 18:38:17 INFO - Current package versions: 18:38:17 INFO - blessings == 1.6 18:38:17 INFO - blobuploader == 1.2.4 18:38:17 INFO - docopt == 0.6.1 18:38:17 INFO - manifestparser == 1.1 18:38:17 INFO - mozInstall == 1.12 18:38:17 INFO - mozcrash == 0.17 18:38:17 INFO - mozdebug == 0.1 18:38:17 INFO - mozdevice == 0.48 18:38:17 INFO - mozfile == 1.2 18:38:17 INFO - mozhttpd == 0.7 18:38:17 INFO - mozinfo == 0.9 18:38:17 INFO - mozleak == 0.1 18:38:17 INFO - mozlog == 3.2 18:38:17 INFO - moznetwork == 0.27 18:38:17 INFO - mozprocess == 0.22 18:38:17 INFO - mozprofile == 0.28 18:38:17 INFO - mozrunner == 6.11 18:38:17 INFO - mozscreenshot == 0.1 18:38:17 INFO - mozsystemmonitor == 0.1 18:38:17 INFO - moztest == 0.7 18:38:17 INFO - mozversion == 1.4 18:38:17 INFO - psutil == 3.1.1 18:38:17 INFO - requests == 1.2.3 18:38:17 INFO - wsgiref == 0.1.2 18:38:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38: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')]} 18:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:17 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:38:17 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 18:38:17 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 18:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:17 INFO - 'HOME': '/Users/cltbld', 18:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:17 INFO - 'LOGNAME': 'cltbld', 18:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:17 INFO - 'MOZ_NO_REMOTE': '1', 18:38:17 INFO - 'NO_EM_RESTART': '1', 18:38:17 INFO - 'PAGER': '/bin/cat', 18:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:17 INFO - 'PWD': '/builds/slave/test', 18:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:17 INFO - 'SHELL': '/bin/bash', 18:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:38:17 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:17 INFO - 'USER': 'cltbld', 18:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:17 INFO - 'XPC_FLAGS': '0x0', 18:38:17 INFO - 'XPC_SERVICE_NAME': '0', 18:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 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)) 18:38:17 INFO - Cleaning up... 18:38:17 INFO - Return code: 0 18:38:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:38: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')]} 18:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38: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')]} 18:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:17 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:38:17 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 18:38:17 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 18:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:17 INFO - 'HOME': '/Users/cltbld', 18:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:17 INFO - 'LOGNAME': 'cltbld', 18:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:17 INFO - 'MOZ_NO_REMOTE': '1', 18:38:17 INFO - 'NO_EM_RESTART': '1', 18:38:17 INFO - 'PAGER': '/bin/cat', 18:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:17 INFO - 'PWD': '/builds/slave/test', 18:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:17 INFO - 'SHELL': '/bin/bash', 18:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:38:17 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:17 INFO - 'USER': 'cltbld', 18:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:17 INFO - 'XPC_FLAGS': '0x0', 18:38:17 INFO - 'XPC_SERVICE_NAME': '0', 18:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:17 INFO - Downloading/unpacking jsonschema 18:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:38:19 INFO - Downloading/unpacking functools32 (from jsonschema) 18:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:20 INFO - Downloading functools32-3.2.3-2.tar.gz 18:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:38:20 INFO - warning: no files found matching '*.txt' 18:38:20 INFO - no previously-included directories found matching 'build' 18:38:20 INFO - no previously-included directories found matching 'dist' 18:38:20 INFO - no previously-included directories found matching '.git*' 18:38:20 INFO - Installing collected packages: jsonschema, functools32 18:38:20 INFO - Running setup.py install for functools32 18:38:20 INFO - warning: no files found matching '*.txt' 18:38:20 INFO - no previously-included directories found matching 'build' 18:38:20 INFO - no previously-included directories found matching 'dist' 18:38:20 INFO - no previously-included directories found matching '.git*' 18:38:20 INFO - Successfully installed jsonschema functools32 18:38:20 INFO - Cleaning up... 18:38:20 INFO - Return code: 0 18:38:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:20 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 0x101264a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10126a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0c8c978c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, '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.oBSGYuErr6/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.ctKzSeOH2I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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 18:38:20 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 18:38:20 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 18:38:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:38:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:20 INFO - 'HOME': '/Users/cltbld', 18:38:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:20 INFO - 'LOGNAME': 'cltbld', 18:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:20 INFO - 'MOZ_NO_REMOTE': '1', 18:38:20 INFO - 'NO_EM_RESTART': '1', 18:38:20 INFO - 'PAGER': '/bin/cat', 18:38:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:20 INFO - 'PWD': '/builds/slave/test', 18:38:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:20 INFO - 'SHELL': '/bin/bash', 18:38:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:38:20 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:38:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:20 INFO - 'USER': 'cltbld', 18:38:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:20 INFO - 'XPC_FLAGS': '0x0', 18:38:20 INFO - 'XPC_SERVICE_NAME': '0', 18:38:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:21 INFO - Downloading/unpacking flake8 18:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:38:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:38:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:38:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:38:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:38:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:38:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:38:24 INFO - no previously-included directories found matching 'docs/_build' 18:38:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:38:24 INFO - Running setup.py install for pep8 18:38:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:38:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:38:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:38:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:38:25 INFO - no previously-included directories found matching 'docs/_build' 18:38:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:38:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:38:25 INFO - Cleaning up... 18:38:25 INFO - Return code: 0 18:38:25 INFO - Running post-action listener: _resource_record_post_action 18:38:25 INFO - Running post-action listener: _start_resource_monitoring 18:38:25 INFO - Starting resource monitoring. 18:38:25 INFO - ##### 18:38:25 INFO - ##### Finished create-virtualenv step (success) 18:38:25 INFO - ##### 18:38:25 INFO - ##### 18:38:25 INFO - ##### Running install step. 18:38:25 INFO - ##### 18:38:25 INFO - Running pre-action listener: _resource_record_pre_action 18:38:25 INFO - Running main action method: install 18:38:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:38:25 INFO - Reading from file tmpfile_stdout 18:38:25 INFO - Detecting whether we're running mozinstall >=1.0... 18:38:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:38:25 INFO - Reading from file tmpfile_stdout 18:38:25 INFO - Output received: 18:38:25 INFO - Usage: mozinstall [options] installer 18:38:25 INFO - Options: 18:38:25 INFO - -h, --help show this help message and exit 18:38:25 INFO - -d DEST, --destination=DEST 18:38:25 INFO - Directory to install application into. [default: 18:38:25 INFO - "/builds/slave/test"] 18:38:25 INFO - --app=APP Application being installed. [default: firefox] 18:38:25 INFO - mkdir: /builds/slave/test/build/application 18:38:25 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'] 18:38:25 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 18:38:59 INFO - Reading from file tmpfile_stdout 18:38:59 INFO - Output received: 18:38:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:38:59 INFO - Running post-action listener: _resource_record_post_action 18:38:59 INFO - ##### 18:38:59 INFO - ##### Finished install step (success) 18:38:59 INFO - ##### 18:38:59 INFO - ##### 18:38:59 INFO - ##### Running run-tests step. 18:38:59 INFO - ##### 18:38:59 INFO - Running pre-action listener: _resource_record_pre_action 18:38:59 INFO - Running main action method: run_tests 18:38:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:38:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:38:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:38:59 INFO - 2016-05-13 18:38:59.140 screenresolution[1918:9715] starting screenresolution argv=screenresolution get 18:38:59 INFO - 2016-05-13 18:38:59.142 screenresolution[1918:9715] Display 0: 1600x1200x32@60 18:38:59 INFO - 2016-05-13 18:38:59.148 screenresolution[1919:9718] starting screenresolution argv=screenresolution list 18:38:59 INFO - Available Modes on Display 0 18:38:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:38:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:38:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:38:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:38:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:38:59 INFO - Intel Iris: 18:38:59 INFO - Chipset Model: Intel Iris 18:38:59 INFO - Type: GPU 18:38:59 INFO - Bus: Built-In 18:38:59 INFO - VRAM (Dynamic, Max): 1536 MB 18:38:59 INFO - Vendor: Intel (0x8086) 18:38:59 INFO - Device ID: 0x0a2e 18:38:59 INFO - Revision ID: 0x0009 18:38:59 INFO - Displays: 18:38:59 INFO - Display: 18:38:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:38:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:38:59 INFO - Display Serial Number: 1600x1200 60 18:38:59 INFO - Main Display: Yes 18:38:59 INFO - Mirror: Off 18:38:59 INFO - Online: Yes 18:38:59 INFO - Rotation: Supported 18:38:59 INFO - Return code: 0 18:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:38:59 INFO - Python 2.7.3 18:38:59 INFO - Return code: 0 18:38:59 INFO - grabbing minidump binary from tooltool 18:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:38:59 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 0x101108d30>, '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 0x100f2cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010ffb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:38:59 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 18:38:59 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 18:38:59 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 18:38:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:38:59 INFO - Return code: 0 18:38:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:38:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:38:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:38:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:38:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:39:00 INFO - Return code: 0 18:39:00 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/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 18:39:00 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/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0113 --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 18:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oBSGYuErr6/Render', 18:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:00 INFO - 'HOME': '/Users/cltbld', 18:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:00 INFO - 'LOGNAME': 'cltbld', 18:39:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:00 INFO - 'MOZ_NO_REMOTE': '1', 18:39:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:00 INFO - 'NO_EM_RESTART': '1', 18:39:00 INFO - 'PAGER': '/bin/cat', 18:39:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:00 INFO - 'PWD': '/builds/slave/test', 18:39:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:00 INFO - 'SHELL': '/bin/bash', 18:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctKzSeOH2I/Listeners', 18:39:00 INFO - 'TMPDIR': '/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/', 18:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:00 INFO - 'USER': 'cltbld', 18:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:00 INFO - 'XPC_FLAGS': '0x0', 18:39:00 INFO - 'XPC_SERVICE_NAME': '0', 18:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:00 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/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0113', '--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 18:39:00 INFO - mozversion application_buildid: 20160513164442 18:39:00 INFO - mozversion application_changeset: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 18:39:00 INFO - mozversion application_display_name: Nightly 18:39:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:39:00 INFO - mozversion application_name: Firefox 18:39:00 INFO - mozversion application_remotingname: firefox 18:39:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:39:00 INFO - mozversion application_vendor: Mozilla 18:39:00 INFO - mozversion application_version: 49.0a1 18:39:00 INFO - mozversion platform_buildid: 20160513164442 18:39:00 INFO - mozversion platform_changeset: 2afd8fa9bb5df5577e5566468bb423b76c63cc77 18:39:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:39:00 INFO - mozversion platform_version: 49.0a1 18:39:00 INFO - using testdate: 1463189940 18:39:00 INFO - actual date: 1463189940 18:39:00 INFO - starting webserver on 'localhost:49391' 18:39:00 INFO - SUITE-START | Running 2 tests 18:39:00 INFO - TEST-START | dromaeo_css 18:39:00 INFO - operating with platform_type : mac_ 18:39:00 INFO - Initialising browser for dromaeo_css test... 18:39:00 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp96ISZQ/profile http://localhost:49391/getInfo.html) 18:39:02 INFO - PROCESS | 1937 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1273: ReferenceError: E10SUINotification is not defined 18:39:03 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 18:39:03 INFO - PROCESS | 1937 | colorDepth:24 18:39:03 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 18:39:03 INFO - PROCESS | 1937 | __metrics 18:39:03 ERROR - PROCESS | 1937 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:39:04 INFO - TEST-INFO | 1937: exit 0 18:39:04 INFO - Browser initialized. 18:39:04 INFO - Running cycle 1/1 for dromaeo_css test... 18:39:04 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmp96ISZQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:16 INFO - PROCESS | 1940 | RSS: Main: 249978880 18:39:16 INFO - PROCESS | 1940 | 18:40:06 INFO - PROCESS | 1940 | 1463190006031 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] 18:42:17 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49391/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49391/tests/dromaeo/cssquery-ext.html) 18:42:18 INFO - PROCESS | 1940 | RSS: Main: 264413184 18:42:18 INFO - PROCESS | 1940 | 18:45:21 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49391/tests/dromaeo/cssquery-ext.html (next: http://localhost:49391/tests/dromaeo/cssquery-jquery.html) 18:45:21 INFO - PROCESS | 1940 | RSS: Main: 275177472 18:45:21 INFO - PROCESS | 1940 | 18:48:23 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49391/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49391/tests/dromaeo/cssquery-mootools.html) 18:48:24 INFO - PROCESS | 1940 | RSS: Main: 277159936 18:48:24 INFO - PROCESS | 1940 | 18:51:26 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49391/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49391/tests/dromaeo/cssquery-prototype.html) 18:51:26 INFO - PROCESS | 1940 | RSS: Main: 278786048 18:51:26 INFO - PROCESS | 1940 | 18:54:29 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49391/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49391/tests/dromaeo/cssquery-yui.html) 18:54:30 INFO - PROCESS | 1940 | RSS: Main: 281587712 18:54:30 INFO - PROCESS | 1940 | 18:57:32 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:49391/tests/dromaeo/cssquery-yui.html (next: http://localhost:49391/tests/dromaeo/cssquery-dojo.html) 18:57:33 INFO - PROCESS | 1940 | RSS: Main: 282775552 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | __start_tp_report 18:57:33 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 18:57:33 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 18:57:33 INFO - PROCESS | 1940 | |i|pagename|runs| 18:57:33 INFO - PROCESS | 1940 | |0;dojo.html;3454.10193349729;3468.0289518934705;3504.6845783879453;3522.6653467920537;3536.257385948949;5768.134779783029;5862.973979553577;5877.441643043915;5895.705214739262;5920.585559010871;5729.026065568853;5831.737571809268;5839.386864379243;5872.823815285548;5874.618149820259;5191.195364718475;5254.710990895499;5336.59975501837;5359.330083739533;5385.973070134663;324.2184978808544;5319.840404787863;5341.866453338659;5344.91982620261;5414.83755487336;5260.1583746600745;5270.578353731692;5272.499112584298;5304.283921670563;5331.706756128411;5207.453217412155;5307;5334.146536554133;5340.91988620171;5344.973275133639;4324.589164029412;5082.669626474304;5200.3759548854005;5229.111051121318;5239.397469291042;5060.190369540893;5668.348139963916;5715.799947001877;5818.3308919474375;5940.613860099123;6235.87528249437;6269.498739123088;6292.622442653455;6323.74705011799;6336.58812177207;6291.559590828644;6335.651539165344;6336.52476064297;6349.396807303298;6354.87290254197;5312.857735586867;6063.333033366326;6073.30157031947;6079.817605471843;6148.538859585549;6214.39668565513;6243.96878015607;6280.780172693934;6284.717187726564;6311.274203466568;6277.748890044439;6300.558960872787;6301.7164227609865;6310.116583678288;6321.905171422432;6004.8590767753985;6312.621242725451;6331.3352098029045;6353.809385718436;6371.33101024395;5448.210009548593;6276.686165691788;6305.464035557029;6334.144890439824;6347.460465860454;6186.566940314225;6249.843753906098;6268.122462855204;6326.335734747785;6343.904841427382;4952.182889823211;4961.776720047606;4988.6757360771435;5004.774785134678;5008.949910500921;4429;4447.911041779146;4452.755098469615;4476.686631935733;4490.640748740093;5959.821205363846;6024.728887200086;6040.0033994391315;6048.727807248684;6057.757689692451;6209.844753881189;6353.269374021954;6353.856305864896;6357.300696923335;6357.968210159012;5966.820995370146;5988.610740301867;5995.730192141364;6014.398560143951;6057.545684073712;5534.75093620788;5544.944550554524;5546.528545073714;5547.25112109868;5551.722413879371;6282.591631543935;6405.679716014274;6508.186476690414;6531.379518945598;6554.082428460019;6250.031245157008;6329.208848893888;6342.460890824332;6342.968285158545;6352.428281454691;6478.352164783484;6528.510361722795;6532.791433584612;6533.118029066205;6554.4756419487285;6144.3717414884195;6274.560780745486;6289.40250676185;6302.212223472066;6340.968295158495;6292.528060395397;6314.305426403183;6327.525435592257;6332.588381755171;6349.68251587428;6205.22434695663;6227.813165605039;6332.493400527855;6344.080108384166;6351.745930162927;5149.02168587982;6076.088586712029;6111.694415279308;6133.662648554194;6303.1175635412;6001.189839371804;6078.45293923549;6131.056064924089;6131.835460087213;6143.293521245114;6242.906356404566;6265.248170219603;6276.278228003838;6306.842328941813;6342.207224096988;6069.848253793691;6109.541784366279;6143.477804386677;6145;6215.564910456224 18:57:33 INFO - PROCESS | 1940 | |1;ext.html;517.8161752577835;527.29605976022;535.1464414259257;570.6547538739064;577.904645733454;40221.39667904984;44097.77951110244;44115.77942110289;44169;44357.112857742824;43794.56205437945;44163.77918110405;44666;44848.3272750909;44956.775216123875;9661.227101831853;9808.656697015605;9848.60605575776;9859.964703706115;9861.309708320421;8499.405041647087;9731.562079706395;9840.114389704926;9849.753756156082;9918.454485003322;9808.950955245215;9849.40903545786;9870;9895.109440150385;9900.603975840957;9731.367461115076;9820.950895245549;9859.802803943881;9866.112049915506;9878.357906736112;8138.959305203436;9671.661491847752;9683.612655493771;9724.75688107796;9752.804943901154;33561.83219083914;41592.584074159175;41706;41987.58012419867;41991.58008419907;2736.904208352698;3116.8467666963143;3117.547955546456;3410.6418826023405;3462.5844898612327;29380.55929161043;29838.25404364908;30013;30075;30312.54531182012;2729.8089133760645;2742.684591272009;2748.9725102748916;2773.292810333352;2838.7445129938405;514222;517747;520420;523742;524484;64901;65142.34857651363;65156;65423;65709.34290657032;37087;37394;37917.05207369838;38939.80530097331;68830.3116968834;17487;17596.472105836845;17655.73516397255;17661;17675.939443633422;13256.138351007154;13267.601971940858;13312.26782526951;13348.866511334956;13372.130811497222;20187.788732676087;20284.087216075313;20564.280250191172;20641.587168256552;20698.068586913323;8109.75670729879;8131.878021829677;8132.552709601029;8188.2630563249595;8289.58552072406;935.6818681648273;1009.8182327181178;1019.8317277649105;1066.5413872035099;1109.001898291528;28745.706443210107;29202;30872.53691194634;31603.683963160533;31742.73029078857;1073.9140868730483;1074.3607553505651;1098.6813823990954;1104.3594715065242;1166.2652528906735;16040.636545893729;16070.75893861593;16245.837541624669;16372.67254654924;16560.50318490447;683389;692620;708689;709249;710507;62703;62817;63291;63505.682471587344;63855;1347.2657401716087;1433.8351089624828;1441.2793603198402;1497.3336865094927;1501.3018946189857;30213.942512011974;30292.697073028987;30447.086587402413;30504.542431863098;30601.846990764905;45996;55022;55655;55667;55728;478526;482417;484027;559572;570465;27135.59296610513;27453.588196177472;27488.17535473939;27652.585211221445;27806.44387112287;47229;48049;48187;48663.27005094856;48866.267005994225;39103;39930.40104398378;39996.80001599973;40852.59147408488;40926.795366022976;38513.80743096267;38717.80641096889;38878.805605971786;39895.80312590627;40007.59992400039;38192.80903595464;38820.41769373518;38908;39023;39416.80291598639;29817.25456863596;34032.65967340295;34547;34773.78291759776;35102.824485877405;25300.74699252984;25471.47171169736;25503.744962550878;25885.611715824656;26212.21363359102 18:57:33 INFO - PROCESS | 1940 | |2;jquery.html;351.20276971275206;351.71862509992;388.8483491438338;390.4689622113925;402.98992525186884;15805.84194158058;16549.917250413746;16856.157192140407;16875.746863797052;16907.915460422697;18707.97106159161;20445.386638400836;21635;21857.344279671597;21892.90535473224;5943.702814859251;6076.4834989025985;6144.047672610753;6162.414570615784;6172.567920245585;6062.545309101813;6082.330943596223;6113.7860174893885;6129.754809807602;6150.231221097363;5901;5993.101034844786;6056.303525094627;6062.635906920952;6118.877622447549;5919.704014799265;6037.396260373971;6051.636901785907;6053.969730151343;6083.330833608299;4365.017870979036;5277.8416647500635;5285.339332583427;5287.471252874681;5295.338082739658;25966.831492582878;26108.086216982505;26153.73846261551;26587.936482540674;26626.33434164142;1852.7776666799934;2002.7596688397339;2032.6036422897394;2037.3989673046487;2348.3659411958847;9959.759054212744;10131.696049118538;10227.437490937995;10228.590856365736;11997.34014629195;1724.5774785177587;1740.3734655524129;1746.720524716052;1815.863810214226;1816.9000704961222;122994.38502807518;127187;128497.35751321277;129397.35301323528;130370.3481482596;10521.3687178768;11262.761096279402;11910.761784764256;11913.940430297793;11950.521979120738;15019.699606008036;15084.396624135132;15086.321115549827;15091.54725358241;15109.244537773067;7300.926990730131;8770.824583508294;8836.381453298207;8858.379913406128;8887.377883548219;7158.067321823079;8815.647374105092;8826.338024648048;8937.687180948766;8957.910420895838;4629.823982728112;4656.8602941911795;4676.906461870743;4683.859484215479;4685.859424217211;1709.564061164395;1992.7010948357572;2019.9192032318542;2024.2611446821804;2058.1355830551206;841.6296829395169;902.5712786426395;910.9544522773834;983.7884854756119;1011.9696409107738;16699.33202671904;20319.492012699506;21139.471513211985;21294.680579791315;21420.250291239732;785.8821176823523;926.3145272498438;945.7351941456403;972.8978457262027;1012.2762225009006;10808.51361688726;11180.273282236629;11180.944095279472;11209.887901121048;11226.943865280622;608735;612519;620124.9242807949;626039;627203;77538;124285;137532;139308;144490;975.5951280218771;990.1583653894277;995.7261753017833;999.9100080992746;1061.5010944855906;15945.920270398574;19027.429177124708;19178.712319314942;19467.8053219466;19557;53069.73465132804;53593.19610205928;53713.46286537085;55003.724981374835;55265.72367138138;80755.78866316892;81027.97430064059;81791.59104204678;83246;83691.81348390791;6789;8177.918220817954;8205.179482051746;8217.178282171735;8540.871886921825;10995.615153469591;12649.367531623566;12831.101822872683;13100.148490348098;13143.868561314264;7749.3413059890545;10277.897221027693;11102.500387482422;11276.872312768657;11345.773084538097;10895.891041089488;10940.945295273472;11284.717882053013;11331.206815522833;11744.882551174378;11221.71945701364;11545.422728863692;11772.529098836294;11894.345810980132;11979.161458697807;7231.5299505531975;8282.71010514629;8367.62345694433;8442.029166645669;8550.957245213734;10448.738781530523;11237.55049797999;11257.887421125684;11552.537898483966;11676.182667213214 18:57:33 INFO - PROCESS | 1940 | |3;mootools.html;1559.7036563053023;1608.5576466471723;1883.5197024758684;1971.6943873699581;1981.5937732764785;8313.459625124362;9019.639214431414;9077;9600.183984361329;9600.75998100047;9055.320850936174;9119.179273865351;9227.538623068836;9311.58097885595;9324.114209150137;2449.473363226906;2469.000054977737;2479.0331770609478;2480.1815400917653;2487.838290511111;2427.672264244322;2467.814913881466;2477.417806815397;2483.5777917754026;2486.4405508760474;2448.3144719478487;2448.326710154704;2467.2968204061845;2479.1075212923333;2492.7756501914823;2419.080749315258;2455.8403703759286;2461.3477428481465;2470.1231062972656;2478.479519300949;2433.245693834917;2439.6218586119003;2443.0716327795503;2445.5597992361368;2446.877656117187;9302;9375.671851485165;9381.967983521876;9382.90617093834;9393.76515587109;5514.448555144493;5605.439456054402;5683.090705487143;5696.231008813798;5715.971420142914;3665.2669466106618;3671.596124426311;3717.5724791649036;3763.5483741950866;3767.5290588676417;973.464594473044;975.7412937310861;1020.1787561013381;1023.6110278094351;1047.8794938582084;12594.307313097685;12614.243145411212;12701.364931753375;12726.172798768142;12728.299943503103;4926.8768280793265;4943.826966056171;5014.247862820606;5041.0422019816115;5050.217216331474;10398.27212095146;10563.313384629975;10573.629922952814;10626.787464250827;10648.574057037631;5471.8084867029465;5556.694381809039;5576.414476479992;5587.832365029056;5665.546756259571;4577.313402989578;4921.532454416824;5065.265536497261;5072.365954255718;5093.18509038552;4137.317342638491;4161.667066634723;4210.389493523422;4216.789160541961;4249.383839343278;1352.1076089780697;1374.6494643865751;1376.9655758606116;1386.6949271160329;1387.7016441464932;914.5564401265381;924.4915296586985;933.7478880702308;943.1375774524286;948.9003654616304;5957.434043765922;6021.939780602138;6103.42017508336;6239.563230573816;6252.5935814171935;432.77711978331183;435.5318033114415;436.56343656343654;442.92691705868816;449.119725338334;3923.293807114747;3967.5437324708028;4016.176683779818;4060.837566497307;4074.959250407517;11135.72160695989;11582;11584.304941703525;11585.304881707125;11654.825177622342;11344.376059316652;11393.601223957121;11427.771584540935;11517;11546.59586914454;7289.599072050982;8689.087645797226;8704.695335663222;8717.215450609476;8772;5973.372795856372;6007.249093863267;6179.0422484514975;6201.627902325875;6223.37766223374;10258.692239232834;10425.843612345972;10522.421266830397;10638.84041739359;10702.411367374869;10507.42209178472;10704.62533811344;10776.29954052984;10864.185186110915;10893.07408870255;7048.295170483116;7529.736459223901;7785.338246249133;7801.531908085533;7889.526628402315;8675.522846243264;8809.11908809114;8817.647294108165;8876.689315873913;8877.156670116328;7634.732784352521;7824.21757824213;7851.646675899484;7865.174156713462;7967.2431119043595;7557.35762460197;7602.087749470099;7645.082590089002;7773.883391749241;7818.140004599376;7173.318534739192;7263.201047884623;7317.524360916524;7387.4090072795125;7390.889136662847;4847.3698419205275;4970.552650261494;5066.518680725325;5076.263941728354;5098.388193416814;5062.012907483153;5107.744612769421;5158.845234642967;5162.8193013245855;5270.130428479258 18:57:33 INFO - PROCESS | 1940 | |4;prototype.html;272.73272193250614;316.42568737741;316.45885535733896;321.80369974315676;325.38177462820624;4450.866474005777;4872.926906096407;5431.619786614933;5598.972005139979;5648.717564121798;4576.725396476214;4712.622990160788;5481.671099734019;5536.446355364462;5570.832875013756;3522.894313170596;3591.730620203482;3682.3371793077235;3755.586885442599;3837.8272977716;3895.668868146197;4156.672263532431;4162.708610397273;4217.325227963526;4221.282381995068;4141.503019637647;4152.709310348277;4155.958440415602;4164.54190039097;4171.520275168349;3825.158465137665;4169.770662613555;4172.728772629769;4174.958250417502;4221.501367014696;2983.4033193361197;3262.2333751568362;3532.3818331791836;3546.5744110706883;3568.964310356889;5234.7806099249065;5237.554807841338;5263.000029994327;5265.052290587692;5273.34083239595;4095.6313931746286;4549.954500454986;4588.357629931812;4704.835330763441;4753.643476739224;5790.739416726257;5816.167907109289;5859.7656093756195;5894.646321220741;5919.55603329752;3956.3076461619103;3960.6435420812263;3968.9801550992347;3978.110397561164;3982.506560039985;5029.823956161516;5576.904817826105;6157.183630828871;6202.224721909762;6202.348753380875;3244.7890887092262;3246.464333385012;4023.2959232134845;4036.434899114068;4039.656629186552;4039.7576145431367;4053.290674132074;4066.6949978751713;4075.633193012643;4091.118085680561;4763.666543341933;4795.304680821261;4808.61531077513;4808.783604737795;4823.347159062501;5090.643654944192;5162.3030890830005;5746.454086861741;5843.678597677083;5852.385499522574;1191.952321907114;1258.6601617563206;1354.7696891528465;1379.3585982518177;1381.6545863534116;779.6179871862746;800.3557136505169;843.2579330189517;849.6006876767909;902.2150728865929;4783.976080119647;5311.3891902431715;5378.515933565998;5434.64674796137;5532.529734972572;5948.048312270016;6000.459958603659;6001.369856165127;6028.788992385245;6033.939660603338;5199.740012999335;5474.972625136849;5493.368262649847;5615.4946054854445;5685;2883.6539615245883;3199.440097982844;3209.245827230599;3210.7912985655857;3228.6771322867526;5102.591792656579;5205.349331333583;5654.773809047592;6003.849903752441;6364.6181229126405;5230.293910322058;5312.442193569696;5656.830295091154;6344.7324627816715;6386.521010924199;5290.841274761763;6355.459785918249;6388.968055159788;6447.25856526496;6463.927725532978;4852.199387101089;5727.140928860705;5970.2089373187;6018.990504747626;6159.81520554384;5292.6656206589605;5905.5570832186895;6050.0942203207605;6113.602615829956;6136.907946380718;5157.896842063213;5281.762320695655;5968.820935371946;6250.8741433396435;6467.870642587028;4067.593240675909;4126.896827579335;4140.544540100526;4160.979195104005;4171.165766846582;5338.3060202173465;5828.533717302521;5993.670348130895;6016.41294243462;6054.182685337423;4884.78018489176;5432.348118225838;5679.557790150275;5850.356460789395;5914.792982245601;5110.846674599619;5536.889262214814;5808.767649293981;5821.155932389865;5847.473727364558;4599.195140850271;5436.646617969819;5462.562994960473;5662.8301150965535;5689.487946084872;4941.332920055746;5860.15027820955;5889.880922624688;5892.204552385545;5935.673537955454;5726.5418766499415;5757.91363129545;5786.363500015079;5791.565632577489;5824.213731146241 18:57:33 INFO - PROCESS | 1940 | |5;yui.html;620.5563022438956;719.3453956899223;731.9158296795869;760.8592410404073;761.1171041591755;14727.926360368196;15369.69260614787;15498.535043948685;15515.069095854229;15549.144797036173;14159.646008849784;14208;14305.570832875004;14344;14382.640433989156;867.5228624256665;922.7462447826867;938.8544775559765;941.3833938770116;941.9434833909953;733.1532080447065;912.16536868765;919.0992827029515;927.1284992107397;928.2898582584328;976.3604838830578;976.5019839881676;978.5107446276861;978.5694294510392;981.0973904008293;699.912510936133;869.6303322267391;966.7534778631439;967.1199208720066;972.0231167676475;696.7491702986921;698.8183072401162;714.192961952995;729.4164668265366;732.6739600877612;12071.456784444721;14723.852761472355;14844.851551484455;14851.925740371229;14875.553733388017;12379.814302785466;12429.943454806353;12450.875491245062;12480.18878772886;12659.30373829439;8147.633356498913;9914.801703965952;9947.403155810674;9966.352187107816;10270.845937310947;3437.4671925851535;3443.001529556426;3472.28818092293;3477.565304336958;3482.9477557836653;60303;67061.65876682488;85017.37282839644;85382;85481.57259213664;9352.953235233916;9392;9451.102145296185;9675.177609903096;9720.319577629498;21444.356669299945;22135;22153.335399938027;22191.445213869458;22313.884305784646;7375.926240737631;7439.330460258603;7524.774256772305;7543.8114047148165;7663.961680191564;3929.2927273090545;4012.137390461065;4016.5581786003518;4018.557958624549;4041.9191616167514;3557.982210088933;3660.0666829958723;3660.3960346543054;3672.8347224374966;3677.485152078711;537.7391964897022;591.8549955260988;657.7401926239108;659.3176062775004;661.6096503063216;522.9843104706789;560.6467925207094;566.7477972302369;571.345809048637;587.8265911556101;7982.321502672222;8006.879896801553;8031.59842007909;8206.83586328282;8217;573.2748073686789;576.9538436925036;590.9349971503132;602.1239097113689;606.6905878002251;3719.1445967427103;3841.3085644584244;3854.9807250964127;3907.667848232932;4048.817803198863;95556.52221738847;96103;96369.03630963601;96996.0300396987;98061.0193898052;83735.58132209422;83878.16121838825;84254.57872710597;85814.57092714496;86378.70431943526;73822.89265661019;76253.85619215717;76593;76725.61637191779;77294;7497.662605182671;7558.508696934681;7566.205548417337;7566.51788589289;7631.809204769926;32078.839605802754;45927.081458371315;46917.765411172724;47247.29129063136;47356.763216183695;56594;57687;60465;61395;62821;11036.75927957375;11060.391678457761;11106.888931110585;11323.830142547704;11549.383086368083;10296.742581435525;11823.408829558315;11945.940270298594;12229.938850306047;12482.02816789282;11042.33745975244;11381.886181138414;11496.48265828023;11834.970063438659;11917.702057448632;11340.886591134313;11439.485223164811;11675.649730508097;11704.180707350744;11740.00209982127;10789.921007899147;11151.219414640897;11274.210805243882;11326.263792853439;11328.03711684516;8026.478278911852;9715.611375544899;9723.222142228746;9824.705258842247;9918.107370336704;9227.44635321883;9935.022645130317;9936.254780891606;10099.016808439694;10141.239407044353 18:57:33 INFO - PROCESS | 1940 | __end_tp_report 18:57:33 INFO - PROCESS | 1940 | __start_cc_report 18:57:33 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,417 18:57:33 INFO - PROCESS | 1940 | __end_cc_report 18:57:33 INFO - PROCESS | 1940 | __startTimestamp1463191053222__endTimestamp 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | ------- Summary: start ------- 18:57:33 INFO - PROCESS | 1940 | Number of tests: 6 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5816.08 Median:6111.69 stddev:789.36 (12.9%) stddev-sans-first:769.73 18:57:33 INFO - PROCESS | 1940 | Values: 3454.1 3468.0 3504.7 3522.7 3536.3 5768.1 5863.0 5877.4 5895.7 5920.6 5729.0 5831.7 5839.4 5872.8 5874.6 5191.2 5254.7 5336.6 5359.3 5386.0 324.2 5319.8 5341.9 5344.9 5414.8 5260.2 5270.6 5272.5 5304.3 5331.7 5207.5 5307.0 5334.1 5340.9 5345.0 4324.6 5082.7 5200.4 5229.1 5239.4 5060.2 5668.3 5715.8 5818.3 5940.6 6235.9 6269.5 6292.6 6323.7 6336.6 6291.6 6335.7 6336.5 6349.4 6354.9 5312.9 6063.3 6073.3 6079.8 6148.5 6214.4 6244.0 6280.8 6284.7 6311.3 6277.7 6300.6 6301.7 6310.1 6321.9 6004.9 6312.6 6331.3 6353.8 6371.3 5448.2 6276.7 6305.5 6334.1 6347.5 6186.6 6249.8 6268.1 6326.3 6343.9 4952.2 4961.8 4988.7 5004.8 5008.9 4429.0 4447.9 4452.8 4476.7 4490.6 5959.8 6024.7 6040.0 6048.7 6057.8 6209.8 6353.3 6353.9 6357.3 6358.0 5966.8 5988.6 5995.7 6014.4 6057.5 5534.8 5544.9 5546.5 5547.3 5551.7 6282.6 6405.7 6508.2 6531.4 6554.1 6250.0 6329.2 6342.5 6343.0 6352.4 6478.4 6528.5 6532.8 6533.1 6554.5 6144.4 6274.6 6289.4 6302.2 6341.0 6292.5 6314.3 6327.5 6332.6 6349.7 6205.2 6227.8 6332.5 6344.1 6351.7 5149.0 6076.1 6111.7 6133.7 6303.1 6001.2 6078.5 6131.1 6131.8 6143.3 6242.9 6265.2 6276.3 6306.8 6342.2 6069.8 6109.5 6143.5 6145.0 6215.6 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:71350.13 Median:40002.20 stddev:156631.40 (391.6%) stddev-sans-first:156980.50 18:57:33 INFO - PROCESS | 1940 | Values: 517.8 527.3 535.1 570.7 577.9 40221.4 44097.8 44115.8 44169.0 44357.1 43794.6 44163.8 44666.0 44848.3 44956.8 9661.2 9808.7 9848.6 9860.0 9861.3 8499.4 9731.6 9840.1 9849.8 9918.5 9809.0 9849.4 9870.0 9895.1 9900.6 9731.4 9821.0 9859.8 9866.1 9878.4 8139.0 9671.7 9683.6 9724.8 9752.8 33561.8 41592.6 41706.0 41987.6 41991.6 2736.9 3116.8 3117.5 3410.6 3462.6 29380.6 29838.3 30013.0 30075.0 30312.5 2729.8 2742.7 2749.0 2773.3 2838.7 514222.0 517747.0 520420.0 523742.0 524484.0 64901.0 65142.3 65156.0 65423.0 65709.3 37087.0 37394.0 37917.1 38939.8 68830.3 17487.0 17596.5 17655.7 17661.0 17675.9 13256.1 13267.6 13312.3 13348.9 13372.1 20187.8 20284.1 20564.3 20641.6 20698.1 8109.8 8131.9 8132.6 8188.3 8289.6 935.7 1009.8 1019.8 1066.5 1109.0 28745.7 29202.0 30872.5 31603.7 31742.7 1073.9 1074.4 1098.7 1104.4 1166.3 16040.6 16070.8 16245.8 16372.7 16560.5 683389.0 692620.0 708689.0 709249.0 710507.0 62703.0 62817.0 63291.0 63505.7 63855.0 1347.3 1433.8 1441.3 1497.3 1501.3 30213.9 30292.7 30447.1 30504.5 30601.8 45996.0 55022.0 55655.0 55667.0 55728.0 478526.0 482417.0 484027.0 559572.0 570465.0 27135.6 27453.6 27488.2 27652.6 27806.4 47229.0 48049.0 48187.0 48663.3 48866.3 39103.0 39930.4 39996.8 40852.6 40926.8 38513.8 38717.8 38878.8 39895.8 40007.6 38192.8 38820.4 38908.0 39023.0 39416.8 29817.3 34032.7 34547.0 34773.8 35102.8 25300.7 25471.5 25503.7 25885.6 26212.2 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:35878.91 Median:26037.46 stddev:103405.20 (397.1%) stddev-sans-first:103660.86 18:57:33 INFO - PROCESS | 1940 | Values: 351.2 351.7 388.8 390.5 403.0 15805.8 16549.9 16856.2 16875.7 16907.9 18708.0 20445.4 21635.0 21857.3 21892.9 5943.7 6076.5 6144.0 6162.4 6172.6 6062.5 6082.3 6113.8 6129.8 6150.2 5901.0 5993.1 6056.3 6062.6 6118.9 5919.7 6037.4 6051.6 6054.0 6083.3 4365.0 5277.8 5285.3 5287.5 5295.3 25966.8 26108.1 26153.7 26587.9 26626.3 1852.8 2002.8 2032.6 2037.4 2348.4 9959.8 10131.7 10227.4 10228.6 11997.3 1724.6 1740.4 1746.7 1815.9 1816.9 122994.4 127187.0 128497.4 129397.4 130370.3 10521.4 11262.8 11910.8 11913.9 11950.5 15019.7 15084.4 15086.3 15091.5 15109.2 7300.9 8770.8 8836.4 8858.4 8887.4 7158.1 8815.6 8826.3 8937.7 8957.9 4629.8 4656.9 4676.9 4683.9 4685.9 1709.6 1992.7 2019.9 2024.3 2058.1 841.6 902.6 911.0 983.8 1012.0 16699.3 20319.5 21139.5 21294.7 21420.3 785.9 926.3 945.7 972.9 1012.3 10808.5 11180.3 11180.9 11209.9 11226.9 608735.0 612519.0 620124.9 626039.0 627203.0 77538.0 124285.0 137532.0 139308.0 144490.0 975.6 990.2 995.7 999.9 1061.5 15945.9 19027.4 19178.7 19467.8 19557.0 53069.7 53593.2 53713.5 55003.7 55265.7 80755.8 81028.0 81791.6 83246.0 83691.8 6789.0 8177.9 8205.2 8217.2 8540.9 10995.6 12649.4 12831.1 13100.1 13143.9 7749.3 10277.9 11102.5 11276.9 11345.8 10895.9 10940.9 11284.7 11331.2 11744.9 11221.7 11545.4 11772.5 11894.3 11979.2 7231.5 8282.7 8367.6 8442.0 8551.0 10448.7 11237.6 11257.9 11552.5 11676.2 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:6004.26 Median:4924.20 stddev:3443.66 (69.9%) stddev-sans-first:3437.12 18:57:33 INFO - PROCESS | 1940 | Values: 1559.7 1608.6 1883.5 1971.7 1981.6 8313.5 9019.6 9077.0 9600.2 9600.8 9055.3 9119.2 9227.5 9311.6 9324.1 2449.5 2469.0 2479.0 2480.2 2487.8 2427.7 2467.8 2477.4 2483.6 2486.4 2448.3 2448.3 2467.3 2479.1 2492.8 2419.1 2455.8 2461.3 2470.1 2478.5 2433.2 2439.6 2443.1 2445.6 2446.9 9302.0 9375.7 9382.0 9382.9 9393.8 5514.4 5605.4 5683.1 5696.2 5716.0 3665.3 3671.6 3717.6 3763.5 3767.5 973.5 975.7 1020.2 1023.6 1047.9 12594.3 12614.2 12701.4 12726.2 12728.3 4926.9 4943.8 5014.2 5041.0 5050.2 10398.3 10563.3 10573.6 10626.8 10648.6 5471.8 5556.7 5576.4 5587.8 5665.5 4577.3 4921.5 5065.3 5072.4 5093.2 4137.3 4161.7 4210.4 4216.8 4249.4 1352.1 1374.6 1377.0 1386.7 1387.7 914.6 924.5 933.7 943.1 948.9 5957.4 6021.9 6103.4 6239.6 6252.6 432.8 435.5 436.6 442.9 449.1 3923.3 3967.5 4016.2 4060.8 4075.0 11135.7 11582.0 11584.3 11585.3 11654.8 11344.4 11393.6 11427.8 11517.0 11546.6 7289.6 8689.1 8704.7 8717.2 8772.0 5973.4 6007.2 6179.0 6201.6 6223.4 10258.7 10425.8 10522.4 10638.8 10702.4 10507.4 10704.6 10776.3 10864.2 10893.1 7048.3 7529.7 7785.3 7801.5 7889.5 8675.5 8809.1 8817.6 8876.7 8877.2 7634.7 7824.2 7851.6 7865.2 7967.2 7557.4 7602.1 7645.1 7773.9 7818.1 7173.3 7263.2 7317.5 7387.4 7390.9 4847.4 4970.6 5066.5 5076.3 5098.4 5062.0 5107.7 5158.8 5162.8 5270.1 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:4692.20 Median:5264.03 stddev:1471.84 (28.0%) stddev-sans-first:1438.10 18:57:33 INFO - PROCESS | 1940 | Values: 272.7 316.4 316.5 321.8 325.4 4450.9 4872.9 5431.6 5599.0 5648.7 4576.7 4712.6 5481.7 5536.4 5570.8 3522.9 3591.7 3682.3 3755.6 3837.8 3895.7 4156.7 4162.7 4217.3 4221.3 4141.5 4152.7 4156.0 4164.5 4171.5 3825.2 4169.8 4172.7 4175.0 4221.5 2983.4 3262.2 3532.4 3546.6 3569.0 5234.8 5237.6 5263.0 5265.1 5273.3 4095.6 4550.0 4588.4 4704.8 4753.6 5790.7 5816.2 5859.8 5894.6 5919.6 3956.3 3960.6 3969.0 3978.1 3982.5 5029.8 5576.9 6157.2 6202.2 6202.3 3244.8 3246.5 4023.3 4036.4 4039.7 4039.8 4053.3 4066.7 4075.6 4091.1 4763.7 4795.3 4808.6 4808.8 4823.3 5090.6 5162.3 5746.5 5843.7 5852.4 1192.0 1258.7 1354.8 1379.4 1381.7 779.6 800.4 843.3 849.6 902.2 4784.0 5311.4 5378.5 5434.6 5532.5 5948.0 6000.5 6001.4 6028.8 6033.9 5199.7 5475.0 5493.4 5615.5 5685.0 2883.7 3199.4 3209.2 3210.8 3228.7 5102.6 5205.3 5654.8 6003.8 6364.6 5230.3 5312.4 5656.8 6344.7 6386.5 5290.8 6355.5 6389.0 6447.3 6463.9 4852.2 5727.1 5970.2 6019.0 6159.8 5292.7 5905.6 6050.1 6113.6 6136.9 5157.9 5281.8 5968.8 6250.9 6467.9 4067.6 4126.9 4140.5 4161.0 4171.2 5338.3 5828.5 5993.7 6016.4 6054.2 4884.8 5432.3 5679.6 5850.4 5914.8 5110.8 5536.9 5808.8 5821.2 5847.5 4599.2 5436.6 5462.6 5662.8 5689.5 4941.3 5860.2 5889.9 5892.2 5935.7 5726.5 5757.9 5786.4 5791.6 5824.2 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:18252.78 Median:30452.56 stddev:26120.00 (85.8%) stddev-sans-first:26159.72 18:57:33 INFO - PROCESS | 1940 | Values: 620.6 719.3 731.9 760.9 761.1 14727.9 15369.7 15498.5 15515.1 15549.1 14159.6 14208.0 14305.6 14344.0 14382.6 867.5 922.7 938.9 941.4 941.9 733.2 912.2 919.1 927.1 928.3 976.4 976.5 978.5 978.6 981.1 699.9 869.6 966.8 967.1 972.0 696.7 698.8 714.2 729.4 732.7 12071.5 14723.9 14844.9 14851.9 14875.6 12379.8 12429.9 12450.9 12480.2 12659.3 8147.6 9914.8 9947.4 9966.4 10270.8 3437.5 3443.0 3472.3 3477.6 3482.9 60303.0 67061.7 85017.4 85382.0 85481.6 9353.0 9392.0 9451.1 9675.2 9720.3 21444.4 22135.0 22153.3 22191.4 22313.9 7375.9 7439.3 7524.8 7543.8 7664.0 3929.3 4012.1 4016.6 4018.6 4041.9 3558.0 3660.1 3660.4 3672.8 3677.5 537.7 591.9 657.7 659.3 661.6 523.0 560.6 566.7 571.3 587.8 7982.3 8006.9 8031.6 8206.8 8217.0 573.3 577.0 590.9 602.1 606.7 3719.1 3841.3 3855.0 3907.7 4048.8 95556.5 96103.0 96369.0 96996.0 98061.0 83735.6 83878.2 84254.6 85814.6 86378.7 73822.9 76253.9 76593.0 76725.6 77294.0 7497.7 7558.5 7566.2 7566.5 7631.8 32078.8 45927.1 46917.8 47247.3 47356.8 56594.0 57687.0 60465.0 61395.0 62821.0 11036.8 11060.4 11106.9 11323.8 11549.4 10296.7 11823.4 11945.9 12229.9 12482.0 11042.3 11381.9 11496.5 11835.0 11917.7 11340.9 11439.5 11675.6 11704.2 11740.0 10789.9 11151.2 11274.2 11326.3 11328.0 8026.5 9715.6 9723.2 9824.7 9918.1 9227.4 9935.0 9936.3 10099.0 10141.2 18:57:33 INFO - PROCESS | 1940 | -------- Summary: end -------- 18:57:33 INFO - PROCESS | 1940 | 18:57:33 INFO - TEST-INFO | 1940: exit 0 18:57:33 INFO - TEST-OK | dromaeo_css | took 1112979ms 18:57:33 INFO - TEST-START | kraken 18:57:33 INFO - operating with platform_type : mac_ 18:57:33 INFO - Initialising browser for kraken test... 18:57:33 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpr2uNNm/profile http://localhost:49391/getInfo.html) 18:57:35 INFO - PROCESS | 1949 | JavaScript error: resource://app/components/nsBrowserGlue.js, line 1273: ReferenceError: E10SUINotification is not defined 18:57:36 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 18:57:36 INFO - PROCESS | 1949 | colorDepth:24 18:57:36 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 18:57:36 INFO - PROCESS | 1949 | __metrics 18:57:36 ERROR - PROCESS | 1949 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:57:37 INFO - TEST-INFO | 1949: exit 0 18:57:37 INFO - Browser initialized. 18:57:37 INFO - Running cycle 1/1 for kraken test... 18:57:37 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/T/tmpr2uNNm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:49 INFO - PROCESS | 1952 | RSS: Main: 249585664 18:57:49 INFO - PROCESS | 1952 | 18:57:52 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49391/tests/kraken/driver.html?testName=audio-beat-detection) 18:57:53 INFO - PROCESS | 1952 | RSS: Main: 261623808 18:57:53 INFO - PROCESS | 1952 | 18:57:56 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49391/tests/kraken/driver.html?testName=audio-dft) 18:57:56 INFO - PROCESS | 1952 | RSS: Main: 266883072 18:57:56 INFO - PROCESS | 1952 | 18:58:00 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49391/tests/kraken/driver.html?testName=audio-fft) 18:58:00 INFO - PROCESS | 1952 | RSS: Main: 266756096 18:58:00 INFO - PROCESS | 1952 | 18:58:02 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49391/tests/kraken/driver.html?testName=audio-oscillator) 18:58:03 INFO - PROCESS | 1952 | RSS: Main: 267800576 18:58:03 INFO - PROCESS | 1952 | 18:58:05 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49391/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:58:06 INFO - PROCESS | 1952 | RSS: Main: 268464128 18:58:06 INFO - PROCESS | 1952 | 18:58:09 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49391/tests/kraken/driver.html?testName=imaging-darkroom) 18:58:10 INFO - PROCESS | 1952 | RSS: Main: 268353536 18:58:10 INFO - PROCESS | 1952 | 18:58:13 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49391/tests/kraken/driver.html?testName=imaging-desaturate) 18:58:13 INFO - PROCESS | 1952 | RSS: Main: 269131776 18:58:13 INFO - PROCESS | 1952 | 18:58:17 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49391/tests/kraken/driver.html?testName=json-parse-financial) 18:58:17 INFO - PROCESS | 1952 | RSS: Main: 269078528 18:58:17 INFO - PROCESS | 1952 | 18:58:19 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49391/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:58:20 INFO - PROCESS | 1952 | RSS: Main: 268292096 18:58:20 INFO - PROCESS | 1952 | 18:58:21 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:58:22 INFO - PROCESS | 1952 | RSS: Main: 268804096 18:58:22 INFO - PROCESS | 1952 | 18:58:24 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:58:25 INFO - PROCESS | 1952 | RSS: Main: 268972032 18:58:25 INFO - PROCESS | 1952 | 18:58:28 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:58:29 INFO - PROCESS | 1952 | RSS: Main: 268509184 18:58:29 INFO - PROCESS | 1952 | 18:58:32 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:58:32 INFO - PROCESS | 1952 | RSS: Main: 269230080 18:58:32 INFO - PROCESS | 1952 | 18:58:34 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49391/tests/kraken/driver.html?testName=ai-astar) 18:58:35 INFO - PROCESS | 1952 | RSS: Main: 270569472 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | __start_tp_report 18:58:35 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 18:58:35 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 18:58:35 INFO - PROCESS | 1952 | |i|pagename|runs| 18:58:35 INFO - PROCESS | 1952 | |0;ai-astar;115;116;113;168;171;128;107;115;116;125 18:58:35 INFO - PROCESS | 1952 | |1;audio-beat-detection;167;149;173;201;121;141;141;121;168;124 18:58:35 INFO - PROCESS | 1952 | |2;audio-dft;197;201;226;202;196;203;248;222;198;200 18:58:35 INFO - PROCESS | 1952 | |3;audio-fft;69;95;74;173;53;58;59;70;132;55 18:58:35 INFO - PROCESS | 1952 | |4;audio-oscillator;80;91;170;113;65;96;156;116;115;109 18:58:35 INFO - PROCESS | 1952 | |5;imaging-gaussian-blur;91;87;92;89;90;87;97;129;91;89 18:58:35 INFO - PROCESS | 1952 | |6;imaging-darkroom;95;103;147;97;95;96;97;136;95;95 18:58:35 INFO - PROCESS | 1952 | |7;imaging-desaturate;91;95;125;146;89;90;93;91;119;99 18:58:35 INFO - PROCESS | 1952 | |8;json-parse-financial;58;61;58;57;57;58;57;119;60;57 18:58:35 INFO - PROCESS | 1952 | |9;json-stringify-tinderbox;64;65;76;70;81;75;75;98;100;77 18:58:35 INFO - PROCESS | 1952 | |10;stanford-crypto-aes;84;86;86;97;111;107;87;89;74;76 18:58:35 INFO - PROCESS | 1952 | |11;stanford-crypto-ccm;138;138;136;135;137;122;149;150;270;150 18:58:35 INFO - PROCESS | 1952 | |12;stanford-crypto-pbkdf2;168;219;177;164;165;164;166;165;168;162 18:58:35 INFO - PROCESS | 1952 | |13;stanford-crypto-sha256-iterative;59;71;86;67;62;59;92;87;84;63 18:58:35 INFO - PROCESS | 1952 | __end_tp_report 18:58:35 INFO - PROCESS | 1952 | __start_cc_report 18:58:35 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1072 18:58:35 INFO - PROCESS | 1952 | __end_cc_report 18:58:35 INFO - PROCESS | 1952 | __startTimestamp1463191115001__endTimestamp 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | ------- Summary: start ------- 18:58:35 INFO - PROCESS | 1952 | Number of tests: 14 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#0] ai-astar Cycles:10 Average:127.40 Median:120.50 stddev:22.96 (19.1%) stddev-sans-first:23.92 18:58:35 INFO - PROCESS | 1952 | Values: 115.0 116.0 113.0 168.0 171.0 128.0 107.0 115.0 116.0 125.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:158.00 stddev:26.37 (16.7%) stddev-sans-first:27.30 18:58:35 INFO - PROCESS | 1952 | Values: 167.0 149.0 173.0 201.0 121.0 141.0 141.0 121.0 168.0 124.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#2] audio-dft Cycles:10 Average:209.30 Median:202.50 stddev:17.13 (8.5%) stddev-sans-first:17.59 18:58:35 INFO - PROCESS | 1952 | Values: 197.0 201.0 226.0 202.0 196.0 203.0 248.0 222.0 198.0 200.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#3] audio-fft Cycles:10 Average:83.80 Median:64.00 stddev:39.40 (61.6%) stddev-sans-first:41.42 18:58:35 INFO - PROCESS | 1952 | Values: 69.0 95.0 74.0 173.0 53.0 58.0 59.0 70.0 132.0 55.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#4] audio-oscillator Cycles:10 Average:111.10 Median:117.50 stddev:32.07 (27.3%) stddev-sans-first:31.98 18:58:35 INFO - PROCESS | 1952 | Values: 80.0 91.0 170.0 113.0 65.0 96.0 156.0 116.0 115.0 109.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:90.50 stddev:12.56 (13.9%) stddev-sans-first:13.27 18:58:35 INFO - PROCESS | 1952 | Values: 91.0 87.0 92.0 89.0 90.0 87.0 97.0 129.0 91.0 89.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:95.00 stddev:19.25 (20.3%) stddev-sans-first:20.03 18:58:35 INFO - PROCESS | 1952 | Values: 95.0 103.0 147.0 97.0 95.0 96.0 97.0 136.0 95.0 95.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:91.00 stddev:19.48 (21.4%) stddev-sans-first:20.10 18:58:35 INFO - PROCESS | 1952 | Values: 91.0 95.0 125.0 146.0 89.0 90.0 93.0 91.0 119.0 99.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:58.00 stddev:19.30 (33.3%) stddev-sans-first:20.34 18:58:35 INFO - PROCESS | 1952 | Values: 58.0 61.0 58.0 57.0 57.0 58.0 57.0 119.0 60.0 57.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:75.50 stddev:12.22 (16.2%) stddev-sans-first:11.85 18:58:35 INFO - PROCESS | 1952 | Values: 64.0 65.0 76.0 70.0 81.0 75.0 75.0 98.0 100.0 77.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:86.00 stddev:12.06 (14.0%) stddev-sans-first:12.61 18:58:35 INFO - PROCESS | 1952 | Values: 84.0 86.0 86.0 97.0 111.0 107.0 87.0 89.0 74.0 76.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:143.50 stddev:42.16 (29.4%) stddev-sans-first:44.39 18:58:35 INFO - PROCESS | 1952 | Values: 138.0 138.0 136.0 135.0 137.0 122.0 149.0 150.0 270.0 150.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:167.00 stddev:17.09 (10.2%) stddev-sans-first:18.07 18:58:35 INFO - PROCESS | 1952 | Values: 168.0 219.0 177.0 164.0 165.0 164.0 166.0 165.0 168.0 162.0 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - PROCESS | 1952 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:77.50 stddev:12.91 (16.7%) stddev-sans-first:12.66 18:58:35 INFO - PROCESS | 1952 | Values: 59.0 71.0 86.0 67.0 62.0 59.0 92.0 87.0 84.0 63.0 18:58:35 INFO - PROCESS | 1952 | -------- Summary: end -------- 18:58:35 INFO - PROCESS | 1952 | 18:58:35 INFO - TEST-INFO | 1952: exit 0 18:58:35 INFO - TEST-OK | kraken | took 62024ms 18:58:35 INFO - SUITE-END | took 1175s 18:58:36 INFO - Completed test suite (00:19:35) 18:58:36 INFO - javascript benchmark 18:58:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7310.112979732431, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3454.10193349729, 3468.0289518934705, 3504.6845783879453, 3522.6653467920537, 3536.257385948949, 5768.134779783029, 5862.973979553577, 5877.441643043915, 5895.705214739262, 5920.585559010871, 5729.026065568853, 5831.737571809268, 5839.386864379243, 5872.823815285548, 5874.618149820259, 5191.195364718475, 5254.710990895499, 5336.59975501837, 5359.330083739533, 5385.973070134663, 324.2184978808544, 5319.840404787863, 5341.866453338659, 5344.91982620261, 5414.83755487336, 5260.1583746600745, 5270.578353731692, 5272.499112584298, 5304.283921670563, 5331.706756128411, 5207.453217412155, 5307.0, 5334.146536554133, 5340.91988620171, 5344.973275133639, 4324.589164029412, 5082.669626474304, 5200.3759548854005, 5229.111051121318, 5239.397469291042, 5060.190369540893, 5668.348139963916, 5715.799947001877, 5818.3308919474375, 5940.613860099123, 6235.87528249437, 6269.498739123088, 6292.622442653455, 6323.74705011799, 6336.58812177207, 6291.559590828644, 6335.651539165344, 6336.52476064297, 6349.396807303298, 6354.87290254197, 5312.857735586867, 6063.333033366326, 6073.30157031947, 6079.817605471843, 6148.538859585549, 6214.39668565513, 6243.96878015607, 6280.780172693934, 6284.717187726564, 6311.274203466568, 6277.748890044439, 6300.558960872787, 6301.7164227609865, 6310.116583678288, 6321.905171422432, 6004.8590767753985, 6312.621242725451, 6331.3352098029045, 6353.809385718436, 6371.33101024395, 5448.210009548593, 6276.686165691788, 6305.464035557029, 6334.144890439824, 6347.460465860454, 6186.566940314225, 6249.843753906098, 6268.122462855204, 6326.335734747785, 6343.904841427382, 4952.182889823211, 4961.776720047606, 4988.6757360771435, 5004.774785134678, 5008.949910500921, 4429.0, 4447.911041779146, 4452.755098469615, 4476.686631935733, 4490.640748740093, 5959.821205363846, 6024.728887200086, 6040.0033994391315, 6048.727807248684, 6057.757689692451, 6209.844753881189, 6353.269374021954, 6353.856305864896, 6357.300696923335, 6357.968210159012, 5966.820995370146, 5988.610740301867, 5995.730192141364, 6014.398560143951, 6057.545684073712, 5534.75093620788, 5544.944550554524, 5546.528545073714, 5547.25112109868, 5551.722413879371, 6282.591631543935, 6405.679716014274, 6508.186476690414, 6531.379518945598, 6554.082428460019, 6250.031245157008, 6329.208848893888, 6342.460890824332, 6342.968285158545, 6352.428281454691, 6478.352164783484, 6528.510361722795, 6532.791433584612, 6533.118029066205, 6554.4756419487285, 6144.3717414884195, 6274.560780745486, 6289.40250676185, 6302.212223472066, 6340.968295158495, 6292.528060395397, 6314.305426403183, 6327.525435592257, 6332.588381755171, 6349.68251587428, 6205.22434695663, 6227.813165605039, 6332.493400527855, 6344.080108384166, 6351.745930162927, 5149.02168587982, 6076.088586712029, 6111.694415279308, 6133.662648554194, 6303.1175635412, 6001.189839371804, 6078.45293923549, 6131.056064924089, 6131.835460087213, 6143.293521245114, 6242.906356404566, 6265.248170219603, 6276.278228003838, 6306.842328941813, 6342.207224096988, 6069.848253793691, 6109.541784366279, 6143.477804386677, 6145.0, 6215.564910456224], "value": 5767.998670780804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.8161752577835, 527.29605976022, 535.1464414259257, 570.6547538739064, 577.904645733454, 40221.39667904984, 44097.77951110244, 44115.77942110289, 44169.0, 44357.112857742824, 43794.56205437945, 44163.77918110405, 44666.0, 44848.3272750909, 44956.775216123875, 9661.227101831853, 9808.656697015605, 9848.60605575776, 9859.964703706115, 9861.309708320421, 8499.405041647087, 9731.562079706395, 9840.114389704926, 9849.753756156082, 9918.454485003322, 9808.950955245215, 9849.40903545786, 9870.0, 9895.109440150385, 9900.603975840957, 9731.367461115076, 9820.950895245549, 9859.802803943881, 9866.112049915506, 9878.357906736112, 8138.959305203436, 9671.661491847752, 9683.612655493771, 9724.75688107796, 9752.804943901154, 33561.83219083914, 41592.584074159175, 41706.0, 41987.58012419867, 41991.58008419907, 2736.904208352698, 3116.8467666963143, 3117.547955546456, 3410.6418826023405, 3462.5844898612327, 29380.55929161043, 29838.25404364908, 30013.0, 30075.0, 30312.54531182012, 2729.8089133760645, 2742.684591272009, 2748.9725102748916, 2773.292810333352, 2838.7445129938405, 514222.0, 517747.0, 520420.0, 523742.0, 524484.0, 64901.0, 65142.34857651363, 65156.0, 65423.0, 65709.34290657032, 37087.0, 37394.0, 37917.05207369838, 38939.80530097331, 68830.3116968834, 17487.0, 17596.472105836845, 17655.73516397255, 17661.0, 17675.939443633422, 13256.138351007154, 13267.601971940858, 13312.26782526951, 13348.866511334956, 13372.130811497222, 20187.788732676087, 20284.087216075313, 20564.280250191172, 20641.587168256552, 20698.068586913323, 8109.75670729879, 8131.878021829677, 8132.552709601029, 8188.2630563249595, 8289.58552072406, 935.6818681648273, 1009.8182327181178, 1019.8317277649105, 1066.5413872035099, 1109.001898291528, 28745.706443210107, 29202.0, 30872.53691194634, 31603.683963160533, 31742.73029078857, 1073.9140868730483, 1074.3607553505651, 1098.6813823990954, 1104.3594715065242, 1166.2652528906735, 16040.636545893729, 16070.75893861593, 16245.837541624669, 16372.67254654924, 16560.50318490447, 683389.0, 692620.0, 708689.0, 709249.0, 710507.0, 62703.0, 62817.0, 63291.0, 63505.682471587344, 63855.0, 1347.2657401716087, 1433.8351089624828, 1441.2793603198402, 1497.3336865094927, 1501.3018946189857, 30213.942512011974, 30292.697073028987, 30447.086587402413, 30504.542431863098, 30601.846990764905, 45996.0, 55022.0, 55655.0, 55667.0, 55728.0, 478526.0, 482417.0, 484027.0, 559572.0, 570465.0, 27135.59296610513, 27453.588196177472, 27488.17535473939, 27652.585211221445, 27806.44387112287, 47229.0, 48049.0, 48187.0, 48663.27005094856, 48866.267005994225, 39103.0, 39930.40104398378, 39996.80001599973, 40852.59147408488, 40926.795366022976, 38513.80743096267, 38717.80641096889, 38878.805605971786, 39895.80312590627, 40007.59992400039, 38192.80903595464, 38820.41769373518, 38908.0, 39023.0, 39416.80291598639, 29817.25456863596, 34032.65967340295, 34547.0, 34773.78291759776, 35102.824485877405, 25300.74699252984, 25471.47171169736, 25503.744962550878, 25885.611715824656, 26212.21363359102], "value": 20304.049470133556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.20276971275206, 351.71862509992, 388.8483491438338, 390.4689622113925, 402.98992525186884, 15805.84194158058, 16549.917250413746, 16856.157192140407, 16875.746863797052, 16907.915460422697, 18707.97106159161, 20445.386638400836, 21635.0, 21857.344279671597, 21892.90535473224, 5943.702814859251, 6076.4834989025985, 6144.047672610753, 6162.414570615784, 6172.567920245585, 6062.545309101813, 6082.330943596223, 6113.7860174893885, 6129.754809807602, 6150.231221097363, 5901.0, 5993.101034844786, 6056.303525094627, 6062.635906920952, 6118.877622447549, 5919.704014799265, 6037.396260373971, 6051.636901785907, 6053.969730151343, 6083.330833608299, 4365.017870979036, 5277.8416647500635, 5285.339332583427, 5287.471252874681, 5295.338082739658, 25966.831492582878, 26108.086216982505, 26153.73846261551, 26587.936482540674, 26626.33434164142, 1852.7776666799934, 2002.7596688397339, 2032.6036422897394, 2037.3989673046487, 2348.3659411958847, 9959.759054212744, 10131.696049118538, 10227.437490937995, 10228.590856365736, 11997.34014629195, 1724.5774785177587, 1740.3734655524129, 1746.720524716052, 1815.863810214226, 1816.9000704961222, 122994.38502807518, 127187.0, 128497.35751321277, 129397.35301323528, 130370.3481482596, 10521.3687178768, 11262.761096279402, 11910.761784764256, 11913.940430297793, 11950.521979120738, 15019.699606008036, 15084.396624135132, 15086.321115549827, 15091.54725358241, 15109.244537773067, 7300.926990730131, 8770.824583508294, 8836.381453298207, 8858.379913406128, 8887.377883548219, 7158.067321823079, 8815.647374105092, 8826.338024648048, 8937.687180948766, 8957.910420895838, 4629.823982728112, 4656.8602941911795, 4676.906461870743, 4683.859484215479, 4685.859424217211, 1709.564061164395, 1992.7010948357572, 2019.9192032318542, 2024.2611446821804, 2058.1355830551206, 841.6296829395169, 902.5712786426395, 910.9544522773834, 983.7884854756119, 1011.9696409107738, 16699.33202671904, 20319.492012699506, 21139.471513211985, 21294.680579791315, 21420.250291239732, 785.8821176823523, 926.3145272498438, 945.7351941456403, 972.8978457262027, 1012.2762225009006, 10808.51361688726, 11180.273282236629, 11180.944095279472, 11209.887901121048, 11226.943865280622, 608735.0, 612519.0, 620124.9242807949, 626039.0, 627203.0, 77538.0, 124285.0, 137532.0, 139308.0, 144490.0, 975.5951280218771, 990.1583653894277, 995.7261753017833, 999.9100080992746, 1061.5010944855906, 15945.920270398574, 19027.429177124708, 19178.712319314942, 19467.8053219466, 19557.0, 53069.73465132804, 53593.19610205928, 53713.46286537085, 55003.724981374835, 55265.72367138138, 80755.78866316892, 81027.97430064059, 81791.59104204678, 83246.0, 83691.81348390791, 6789.0, 8177.918220817954, 8205.179482051746, 8217.178282171735, 8540.871886921825, 10995.615153469591, 12649.367531623566, 12831.101822872683, 13100.148490348098, 13143.868561314264, 7749.3413059890545, 10277.897221027693, 11102.500387482422, 11276.872312768657, 11345.773084538097, 10895.891041089488, 10940.945295273472, 11284.717882053013, 11331.206815522833, 11744.882551174378, 11221.71945701364, 11545.422728863692, 11772.529098836294, 11894.345810980132, 11979.161458697807, 7231.5299505531975, 8282.71010514629, 8367.62345694433, 8442.029166645669, 8550.957245213734, 10448.738781530523, 11237.55049797999, 11257.887421125684, 11552.537898483966, 11676.182667213214], "value": 9575.537875032973, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1559.7036563053023, 1608.5576466471723, 1883.5197024758684, 1971.6943873699581, 1981.5937732764785, 8313.459625124362, 9019.639214431414, 9077.0, 9600.183984361329, 9600.75998100047, 9055.320850936174, 9119.179273865351, 9227.538623068836, 9311.58097885595, 9324.114209150137, 2449.473363226906, 2469.000054977737, 2479.0331770609478, 2480.1815400917653, 2487.838290511111, 2427.672264244322, 2467.814913881466, 2477.417806815397, 2483.5777917754026, 2486.4405508760474, 2448.3144719478487, 2448.326710154704, 2467.2968204061845, 2479.1075212923333, 2492.7756501914823, 2419.080749315258, 2455.8403703759286, 2461.3477428481465, 2470.1231062972656, 2478.479519300949, 2433.245693834917, 2439.6218586119003, 2443.0716327795503, 2445.5597992361368, 2446.877656117187, 9302.0, 9375.671851485165, 9381.967983521876, 9382.90617093834, 9393.76515587109, 5514.448555144493, 5605.439456054402, 5683.090705487143, 5696.231008813798, 5715.971420142914, 3665.2669466106618, 3671.596124426311, 3717.5724791649036, 3763.5483741950866, 3767.5290588676417, 973.464594473044, 975.7412937310861, 1020.1787561013381, 1023.6110278094351, 1047.8794938582084, 12594.307313097685, 12614.243145411212, 12701.364931753375, 12726.172798768142, 12728.299943503103, 4926.8768280793265, 4943.826966056171, 5014.247862820606, 5041.0422019816115, 5050.217216331474, 10398.27212095146, 10563.313384629975, 10573.629922952814, 10626.787464250827, 10648.574057037631, 5471.8084867029465, 5556.694381809039, 5576.414476479992, 5587.832365029056, 5665.546756259571, 4577.313402989578, 4921.532454416824, 5065.265536497261, 5072.365954255718, 5093.18509038552, 4137.317342638491, 4161.667066634723, 4210.389493523422, 4216.789160541961, 4249.383839343278, 1352.1076089780697, 1374.6494643865751, 1376.9655758606116, 1386.6949271160329, 1387.7016441464932, 914.5564401265381, 924.4915296586985, 933.7478880702308, 943.1375774524286, 948.9003654616304, 5957.434043765922, 6021.939780602138, 6103.42017508336, 6239.563230573816, 6252.5935814171935, 432.77711978331183, 435.5318033114415, 436.56343656343654, 442.92691705868816, 449.119725338334, 3923.293807114747, 3967.5437324708028, 4016.176683779818, 4060.837566497307, 4074.959250407517, 11135.72160695989, 11582.0, 11584.304941703525, 11585.304881707125, 11654.825177622342, 11344.376059316652, 11393.601223957121, 11427.771584540935, 11517.0, 11546.59586914454, 7289.599072050982, 8689.087645797226, 8704.695335663222, 8717.215450609476, 8772.0, 5973.372795856372, 6007.249093863267, 6179.0422484514975, 6201.627902325875, 6223.37766223374, 10258.692239232834, 10425.843612345972, 10522.421266830397, 10638.84041739359, 10702.411367374869, 10507.42209178472, 10704.62533811344, 10776.29954052984, 10864.185186110915, 10893.07408870255, 7048.295170483116, 7529.736459223901, 7785.338246249133, 7801.531908085533, 7889.526628402315, 8675.522846243264, 8809.11908809114, 8817.647294108165, 8876.689315873913, 8877.156670116328, 7634.732784352521, 7824.21757824213, 7851.646675899484, 7865.174156713462, 7967.2431119043595, 7557.35762460197, 7602.087749470099, 7645.082590089002, 7773.883391749241, 7818.140004599376, 7173.318534739192, 7263.201047884623, 7317.524360916524, 7387.4090072795125, 7390.889136662847, 4847.3698419205275, 4970.552650261494, 5066.518680725325, 5076.263941728354, 5098.388193416814, 5062.012907483153, 5107.744612769421, 5158.845234642967, 5162.8193013245855, 5270.130428479258], "value": 4710.206272646121, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.73272193250614, 316.42568737741, 316.45885535733896, 321.80369974315676, 325.38177462820624, 4450.866474005777, 4872.926906096407, 5431.619786614933, 5598.972005139979, 5648.717564121798, 4576.725396476214, 4712.622990160788, 5481.671099734019, 5536.446355364462, 5570.832875013756, 3522.894313170596, 3591.730620203482, 3682.3371793077235, 3755.586885442599, 3837.8272977716, 3895.668868146197, 4156.672263532431, 4162.708610397273, 4217.325227963526, 4221.282381995068, 4141.503019637647, 4152.709310348277, 4155.958440415602, 4164.54190039097, 4171.520275168349, 3825.158465137665, 4169.770662613555, 4172.728772629769, 4174.958250417502, 4221.501367014696, 2983.4033193361197, 3262.2333751568362, 3532.3818331791836, 3546.5744110706883, 3568.964310356889, 5234.7806099249065, 5237.554807841338, 5263.000029994327, 5265.052290587692, 5273.34083239595, 4095.6313931746286, 4549.954500454986, 4588.357629931812, 4704.835330763441, 4753.643476739224, 5790.739416726257, 5816.167907109289, 5859.7656093756195, 5894.646321220741, 5919.55603329752, 3956.3076461619103, 3960.6435420812263, 3968.9801550992347, 3978.110397561164, 3982.506560039985, 5029.823956161516, 5576.904817826105, 6157.183630828871, 6202.224721909762, 6202.348753380875, 3244.7890887092262, 3246.464333385012, 4023.2959232134845, 4036.434899114068, 4039.656629186552, 4039.7576145431367, 4053.290674132074, 4066.6949978751713, 4075.633193012643, 4091.118085680561, 4763.666543341933, 4795.304680821261, 4808.61531077513, 4808.783604737795, 4823.347159062501, 5090.643654944192, 5162.3030890830005, 5746.454086861741, 5843.678597677083, 5852.385499522574, 1191.952321907114, 1258.6601617563206, 1354.7696891528465, 1379.3585982518177, 1381.6545863534116, 779.6179871862746, 800.3557136505169, 843.2579330189517, 849.6006876767909, 902.2150728865929, 4783.976080119647, 5311.3891902431715, 5378.515933565998, 5434.64674796137, 5532.529734972572, 5948.048312270016, 6000.459958603659, 6001.369856165127, 6028.788992385245, 6033.939660603338, 5199.740012999335, 5474.972625136849, 5493.368262649847, 5615.4946054854445, 5685.0, 2883.6539615245883, 3199.440097982844, 3209.245827230599, 3210.7912985655857, 3228.6771322867526, 5102.591792656579, 5205.349331333583, 5654.773809047592, 6003.849903752441, 6364.6181229126405, 5230.293910322058, 5312.442193569696, 5656.830295091154, 6344.7324627816715, 6386.521010924199, 5290.841274761763, 6355.459785918249, 6388.968055159788, 6447.25856526496, 6463.927725532978, 4852.199387101089, 5727.140928860705, 5970.2089373187, 6018.990504747626, 6159.81520554384, 5292.6656206589605, 5905.5570832186895, 6050.0942203207605, 6113.602615829956, 6136.907946380718, 5157.896842063213, 5281.762320695655, 5968.820935371946, 6250.8741433396435, 6467.870642587028, 4067.593240675909, 4126.896827579335, 4140.544540100526, 4160.979195104005, 4171.165766846582, 5338.3060202173465, 5828.533717302521, 5993.670348130895, 6016.41294243462, 6054.182685337423, 4884.78018489176, 5432.348118225838, 5679.557790150275, 5850.356460789395, 5914.792982245601, 5110.846674599619, 5536.889262214814, 5808.767649293981, 5821.155932389865, 5847.473727364558, 4599.195140850271, 5436.646617969819, 5462.562994960473, 5662.8301150965535, 5689.487946084872, 4941.332920055746, 5860.15027820955, 5889.880922624688, 5892.204552385545, 5935.673537955454, 5726.5418766499415, 5757.91363129545, 5786.363500015079, 5791.565632577489, 5824.213731146241], "value": 4213.9139166964405, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.5563022438956, 719.3453956899223, 731.9158296795869, 760.8592410404073, 761.1171041591755, 14727.926360368196, 15369.69260614787, 15498.535043948685, 15515.069095854229, 15549.144797036173, 14159.646008849784, 14208.0, 14305.570832875004, 14344.0, 14382.640433989156, 867.5228624256665, 922.7462447826867, 938.8544775559765, 941.3833938770116, 941.9434833909953, 733.1532080447065, 912.16536868765, 919.0992827029515, 927.1284992107397, 928.2898582584328, 976.3604838830578, 976.5019839881676, 978.5107446276861, 978.5694294510392, 981.0973904008293, 699.912510936133, 869.6303322267391, 966.7534778631439, 967.1199208720066, 972.0231167676475, 696.7491702986921, 698.8183072401162, 714.192961952995, 729.4164668265366, 732.6739600877612, 12071.456784444721, 14723.852761472355, 14844.851551484455, 14851.925740371229, 14875.553733388017, 12379.814302785466, 12429.943454806353, 12450.875491245062, 12480.18878772886, 12659.30373829439, 8147.633356498913, 9914.801703965952, 9947.403155810674, 9966.352187107816, 10270.845937310947, 3437.4671925851535, 3443.001529556426, 3472.28818092293, 3477.565304336958, 3482.9477557836653, 60303.0, 67061.65876682488, 85017.37282839644, 85382.0, 85481.57259213664, 9352.953235233916, 9392.0, 9451.102145296185, 9675.177609903096, 9720.319577629498, 21444.356669299945, 22135.0, 22153.335399938027, 22191.445213869458, 22313.884305784646, 7375.926240737631, 7439.330460258603, 7524.774256772305, 7543.8114047148165, 7663.961680191564, 3929.2927273090545, 4012.137390461065, 4016.5581786003518, 4018.557958624549, 4041.9191616167514, 3557.982210088933, 3660.0666829958723, 3660.3960346543054, 3672.8347224374966, 3677.485152078711, 537.7391964897022, 591.8549955260988, 657.7401926239108, 659.3176062775004, 661.6096503063216, 522.9843104706789, 560.6467925207094, 566.7477972302369, 571.345809048637, 587.8265911556101, 7982.321502672222, 8006.879896801553, 8031.59842007909, 8206.83586328282, 8217.0, 573.2748073686789, 576.9538436925036, 590.9349971503132, 602.1239097113689, 606.6905878002251, 3719.1445967427103, 3841.3085644584244, 3854.9807250964127, 3907.667848232932, 4048.817803198863, 95556.52221738847, 96103.0, 96369.03630963601, 96996.0300396987, 98061.0193898052, 83735.58132209422, 83878.16121838825, 84254.57872710597, 85814.57092714496, 86378.70431943526, 73822.89265661019, 76253.85619215717, 76593.0, 76725.61637191779, 77294.0, 7497.662605182671, 7558.508696934681, 7566.205548417337, 7566.51788589289, 7631.809204769926, 32078.839605802754, 45927.081458371315, 46917.765411172724, 47247.29129063136, 47356.763216183695, 56594.0, 57687.0, 60465.0, 61395.0, 62821.0, 11036.75927957375, 11060.391678457761, 11106.888931110585, 11323.830142547704, 11549.383086368083, 10296.742581435525, 11823.408829558315, 11945.940270298594, 12229.938850306047, 12482.02816789282, 11042.33745975244, 11381.886181138414, 11496.48265828023, 11834.970063438659, 11917.702057448632, 11340.886591134313, 11439.485223164811, 11675.649730508097, 11704.180707350744, 11740.00209982127, 10789.921007899147, 11151.219414640897, 11274.210805243882, 11326.263792853439, 11328.03711684516, 8026.478278911852, 9715.611375544899, 9723.222142228746, 9824.705258842247, 9918.107370336704, 9227.44635321883, 9935.022645130317, 9936.254780891606, 10099.016808439694, 10141.239407044353], "value": 6854.9417780533295, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1615.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 113.0, 168.0, 171.0, 128.0, 107.0, 115.0, 116.0, 125.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 149.0, 173.0, 201.0, 121.0, 141.0, 141.0, 121.0, 168.0, 124.0], "value": 150.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 226.0, 202.0, 196.0, 203.0, 248.0, 222.0, 198.0, 200.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 95.0, 74.0, 173.0, 53.0, 58.0, 59.0, 70.0, 132.0, 55.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 170.0, 113.0, 65.0, 96.0, 156.0, 116.0, 115.0, 109.0], "value": 111.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 92.0, 89.0, 90.0, 87.0, 97.0, 129.0, 91.0, 89.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 103.0, 147.0, 97.0, 95.0, 96.0, 97.0, 136.0, 95.0, 95.0], "value": 105.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 125.0, 146.0, 89.0, 90.0, 93.0, 91.0, 119.0, 99.0], "value": 103.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 58.0, 57.0, 57.0, 58.0, 57.0, 119.0, 60.0, 57.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 76.0, 70.0, 81.0, 75.0, 75.0, 98.0, 100.0, 77.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 97.0, 111.0, 107.0, 87.0, 89.0, 74.0, 76.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 136.0, 135.0, 137.0, 122.0, 149.0, 150.0, 270.0, 150.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 219.0, 177.0, 164.0, 165.0, 164.0, 166.0, 165.0, 168.0, 162.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 71.0, 86.0, 67.0, 62.0, 59.0, 92.0, 87.0, 84.0, 63.0], "value": 73.0, "unit": "score"}]}]} 18:58:36 INFO - Return code: 0 18:58:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:58:36 ERROR - # TBPL SUCCESS # 18:58:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:58:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:58:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:58:36 INFO - 2016-05-13 18:58:36.307 screenresolution[1957:12584] starting screenresolution argv=screenresolution get 18:58:36 INFO - 2016-05-13 18:58:36.310 screenresolution[1957:12584] Display 0: 1600x1200x32@60 18:58:36 INFO - 2016-05-13 18:58:36.316 screenresolution[1958:12587] starting screenresolution argv=screenresolution list 18:58:36 INFO - Available Modes on Display 0 18:58:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:58:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:58:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:58:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:58:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:58:36 INFO - Intel Iris: 18:58:36 INFO - Chipset Model: Intel Iris 18:58:36 INFO - Type: GPU 18:58:36 INFO - Bus: Built-In 18:58:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:58:36 INFO - Vendor: Intel (0x8086) 18:58:36 INFO - Device ID: 0x0a2e 18:58:36 INFO - Revision ID: 0x0009 18:58:36 INFO - Displays: 18:58:36 INFO - Display: 18:58:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:58:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:58:36 INFO - Display Serial Number: 1600x1200 60 18:58:36 INFO - Main Display: Yes 18:58:36 INFO - Mirror: Off 18:58:36 INFO - Online: Yes 18:58:36 INFO - Rotation: Supported 18:58:36 INFO - Return code: 0 18:58:36 INFO - Running post-action listener: _resource_record_post_action 18:58:36 INFO - ##### 18:58:36 INFO - ##### Finished run-tests step (success) 18:58:36 INFO - ##### 18:58:36 INFO - Running post-run listener: _resource_record_post_run 18:58:36 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 922112; Write bytes: 695982592; Read time: 17; Write time: 2123 18:58:36 INFO - TinderboxPrint: CPU usage
54.6% 18:58:36 INFO - TinderboxPrint: I/O read bytes / time
922,112 / 17 18:58:36 INFO - TinderboxPrint: I/O write bytes / time
695,982,592 / 2,123 18:58:36 INFO - TinderboxPrint: CPU idle
2,200.1 (45.4%) 18:58:36 INFO - TinderboxPrint: CPU system
974.5 (20.1%) 18:58:36 INFO - TinderboxPrint: CPU user
1,667.2 (34.4%) 18:58:36 INFO - TinderboxPrint: Swap in / out
396,361,728 / 0 18:58:36 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375972352; Write bytes: 370382848; Read time: 28117; Write time: 632 18:58:36 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 918016; Write bytes: 298639872; Read time: 17; Write time: 1439 18:58:37 INFO - Running post-run listener: _upload_blobber_files 18:58:37 INFO - Blob upload gear active. 18:58:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:58:37 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 18:58:37 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'] 18:58:37 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 18:58:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:58:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:58:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:58:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:58:38 INFO - (blobuploader) - INFO - Done attempting. 18:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:58:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:58:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:58:39 INFO - (blobuploader) - INFO - Done attempting. 18:58:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:58:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:58:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:58:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:58:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:58:42 INFO - (blobuploader) - INFO - Done attempting. 18:58:42 INFO - (blobuploader) - INFO - Iteration through files over. 18:58:42 INFO - Return code: 0 18:58:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:58:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:58:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58d3f5cf5a3095b1e08ddd1efd3a67a9354b9a448c668cb2c2e0d2f7721a7280a720d80ade08552b2375956a547fabb58b942eead0e70c9b7140e4259b3192fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45db51015b97e9f3604941646358c75a6b101b007dfbc2580df6807bcef881ff40a18c2781ac50defde35a86d638850acaf09763530e4c6f81ce88d34ead06ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af25d8cb38cb99f3cae35c41403a33d23e29fadb7fb75161c4f413ac63bacdf51d871aa9347e8af1e9ff732cc5b99c8fa46b2893e0fcb3dc5dc9cd7fc8c07dc"} 18:58:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:58:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:58:42 INFO - Contents: 18:58:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58d3f5cf5a3095b1e08ddd1efd3a67a9354b9a448c668cb2c2e0d2f7721a7280a720d80ade08552b2375956a547fabb58b942eead0e70c9b7140e4259b3192fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45db51015b97e9f3604941646358c75a6b101b007dfbc2580df6807bcef881ff40a18c2781ac50defde35a86d638850acaf09763530e4c6f81ce88d34ead06ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af25d8cb38cb99f3cae35c41403a33d23e29fadb7fb75161c4f413ac63bacdf51d871aa9347e8af1e9ff732cc5b99c8fa46b2893e0fcb3dc5dc9cd7fc8c07dc"} 18:58:42 INFO - Running post-run listener: copy_logs_to_upload_dir 18:58:42 INFO - Copying logs to upload dir... 18:58:42 INFO - mkdir: /builds/slave/test/build/upload/logs 18:58:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.800056 ========= master_lag: 1.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-13 18:58:43.933393) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 18:58:43.936610) ========= 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.oBSGYuErr6/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.ctKzSeOH2I/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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/58d3f5cf5a3095b1e08ddd1efd3a67a9354b9a448c668cb2c2e0d2f7721a7280a720d80ade08552b2375956a547fabb58b942eead0e70c9b7140e4259b3192fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45db51015b97e9f3604941646358c75a6b101b007dfbc2580df6807bcef881ff40a18c2781ac50defde35a86d638850acaf09763530e4c6f81ce88d34ead06ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af25d8cb38cb99f3cae35c41403a33d23e29fadb7fb75161c4f413ac63bacdf51d871aa9347e8af1e9ff732cc5b99c8fa46b2893e0fcb3dc5dc9cd7fc8c07dc"} build_url:https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/Ti6pqn9xRai3vlxj6bUE2A/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/58d3f5cf5a3095b1e08ddd1efd3a67a9354b9a448c668cb2c2e0d2f7721a7280a720d80ade08552b2375956a547fabb58b942eead0e70c9b7140e4259b3192fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45db51015b97e9f3604941646358c75a6b101b007dfbc2580df6807bcef881ff40a18c2781ac50defde35a86d638850acaf09763530e4c6f81ce88d34ead06ce", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6af25d8cb38cb99f3cae35c41403a33d23e29fadb7fb75161c4f413ac63bacdf51d871aa9347e8af1e9ff732cc5b99c8fa46b2893e0fcb3dc5dc9cd7fc8c07dc"}' ========= master_lag: 0.42 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 18:58:44.367986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:58:44.368296) ========= 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.oBSGYuErr6/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.ctKzSeOH2I/Listeners TMPDIR=/var/folders/1w/z0_4sknj0ws9ng8mf6fqh5bh00000w/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.004869 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:58:44.387779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:58:44.388038) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:58:44.388315) ========= ========= Total master_lag: 1.65 =========