builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0097 starttime: 1464282794.63 results: success (0) buildid: 20160526082142 builduid: d102a8dbda374354bc0ab82f4f781b07 revision: b0096c5c727749ad3e79cbdf20d2e96bd179c213 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.633405) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.633873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.634156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.674562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.674950) ========= 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.MGHz1aIrpL/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.68OOIg8GmR/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-26 10:13:13-- 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% 154M=0s 2016-05-26 10:13:14 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124026 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.818784) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.819126) ========= 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.MGHz1aIrpL/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.68OOIg8GmR/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.020387 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:14.868791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 10:13:14.869144) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b0096c5c727749ad3e79cbdf20d2e96bd179c213 --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 b0096c5c727749ad3e79cbdf20d2e96bd179c213 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MGHz1aIrpL/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.68OOIg8GmR/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-26 10:13:14,222 truncating revision to first 12 chars 2016-05-26 10:13:14,222 Setting DEBUG logging. 2016-05-26 10:13:14,222 attempt 1/10 2016-05-26 10:13:14,222 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b0096c5c7277?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 10:13:15,588 unpacking tar archive at: mozilla-central-b0096c5c7277/testing/mozharness/ program finished with exit code 0 elapsedTime=1.645920 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 10:13:16.536482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:16.537075) ========= script_repo_revision: b0096c5c727749ad3e79cbdf20d2e96bd179c213 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:16.537830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:16.538311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 10:13:16.557021) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-26 10:13:16.557696) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.MGHz1aIrpL/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.68OOIg8GmR/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:13:16 INFO - MultiFileLogger online at 20160526 10:13:16 in /builds/slave/test 10:13:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:13:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:13:16 INFO - {'append_to_log': False, 10:13:16 INFO - 'base_work_dir': '/builds/slave/test', 10:13:16 INFO - 'blob_upload_branch': 'Firefox', 10:13:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:13:16 INFO - 'branch': 'Firefox', 10:13:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:13:16 INFO - 'config_files': ('talos/mac_config.py',), 10:13:16 INFO - 'default_actions': ('clobber', 10:13:16 INFO - 'read-buildbot-config', 10:13:16 INFO - 'download-and-extract', 10:13:16 INFO - 'populate-webroot', 10:13:16 INFO - 'create-virtualenv', 10:13:16 INFO - 'install', 10:13:16 INFO - 'run-tests'), 10:13:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:13:16 INFO - 'download_minidump_stackwalk': True, 10:13:16 INFO - 'download_symbols': 'ondemand', 10:13:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:13:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:13:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:13:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:13:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:13:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:13:16 INFO - 'installer_path': 'installer.exe', 10:13:16 INFO - 'log_level': 'info', 10:13:16 INFO - 'log_name': 'talos', 10:13:16 INFO - 'log_to_console': True, 10:13:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:13:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:13:16 INFO - 'opt_config_files': (), 10:13:16 INFO - 'pip_index': False, 10:13:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:16 INFO - 'cmd': ('bash', 10:13:16 INFO - '-c', 10:13:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:16 INFO - 'enabled': True, 10:13:16 INFO - 'halt_on_failure': False, 10:13:16 INFO - 'name': 'check_screen_resolution'},), 10:13:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:13:16 INFO - 'cmd': ('bash', 10:13:16 INFO - '-c', 10:13:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:13:16 INFO - 'enabled': True, 10:13:16 INFO - 'halt_on_failure': False, 10:13:16 INFO - 'name': 'check_screen_resolution'},), 10:13:16 INFO - 'run_cmd_checks_enabled': True, 10:13:16 INFO - 'sps_profile': False, 10:13:16 INFO - 'sps_profile_interval': 0, 10:13:16 INFO - 'suite': 'dromaeojs', 10:13:16 INFO - 'system_bits': '32', 10:13:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:13:16 INFO - 'title': 't-yosemite-r7-0097', 10:13:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:13:16 INFO - 'use_talos_json': True, 10:13:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:13:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:13:16 INFO - 'work_dir': 'build'} 10:13:16 INFO - ##### 10:13:16 INFO - ##### Running clobber step. 10:13:16 INFO - ##### 10:13:16 INFO - Running pre-action listener: _resource_record_pre_action 10:13:16 INFO - Running main action method: clobber 10:13:16 INFO - rmtree: /builds/slave/test/build 10:13:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:13:17 INFO - Running post-action listener: _resource_record_post_action 10:13:17 INFO - ##### 10:13:17 INFO - ##### Finished clobber step (success) 10:13:17 INFO - ##### 10:13:17 INFO - ##### 10:13:17 INFO - ##### Running read-buildbot-config step. 10:13:17 INFO - ##### 10:13:17 INFO - Running pre-action listener: _resource_record_pre_action 10:13:17 INFO - Running main action method: read_buildbot_config 10:13:17 INFO - Using buildbot properties: 10:13:17 INFO - { 10:13:17 INFO - "project": "", 10:13:17 INFO - "product": "firefox", 10:13:17 INFO - "script_repo_revision": "production", 10:13:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:13:17 INFO - "repository": "", 10:13:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:13:17 INFO - "buildid": "20160526082142", 10:13:17 INFO - "pgo_build": "False", 10:13:17 INFO - "basedir": "/builds/slave/test", 10:13:17 INFO - "buildnumber": 256, 10:13:17 INFO - "slavename": "t-yosemite-r7-0097", 10:13:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:13:17 INFO - "platform": "yosemite_r7", 10:13:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:13:17 INFO - "branch": "mozilla-central", 10:13:17 INFO - "repo_path": "mozilla-central", 10:13:17 INFO - "revision": "b0096c5c727749ad3e79cbdf20d2e96bd179c213", 10:13:17 INFO - "stage_platform": "macosx64", 10:13:17 INFO - "builduid": "d102a8dbda374354bc0ab82f4f781b07", 10:13:17 INFO - "slavebuilddir": "test" 10:13:17 INFO - } 10:13:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 10:13:17 INFO - Running post-action listener: _resource_record_post_action 10:13:17 INFO - ##### 10:13:17 INFO - ##### Finished read-buildbot-config step (success) 10:13:17 INFO - ##### 10:13:17 INFO - ##### 10:13:17 INFO - ##### Running download-and-extract step. 10:13:17 INFO - ##### 10:13:17 INFO - Running pre-action listener: _resource_record_pre_action 10:13:17 INFO - Running main action method: download_and_extract 10:13:17 INFO - mkdir: /builds/slave/test/build/tests 10:13: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')]} 10:13:17 INFO - https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:13:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:13:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 10:13:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:20 INFO - Downloaded 1199 bytes. 10:13:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 10:13:20 INFO - Using the following test package requirements: 10:13:20 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:13:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 10:13:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 10:13:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'jsshell-mac.zip'], 10:13:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 10:13:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 10:13:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 10:13:20 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 10:13:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 10:13:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 10:13:20 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 10:13:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:13: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')]} 10:13:20 INFO - https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:13:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:13:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:13:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:22 INFO - Downloaded 18622094 bytes. 10:13:22 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'] 10:13:22 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 10:13:25 INFO - Return code: 0 10:13:25 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 10:13:25 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')]} 10:13:25 INFO - https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:13:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:13:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 10:13:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:27 INFO - Downloaded 18622094 bytes. 10:13:27 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'] 10:13:27 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 10:13:31 INFO - Return code: 0 10:13: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')]} 10:13:31 INFO - https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:13:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:13:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 10:13:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:32 INFO - Downloaded 10834034 bytes. 10:13:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:13:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:13:32 INFO - Return code: 0 10:13:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:32 INFO - https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:13:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:13:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:13:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 10:13:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LhdLYxqwRv2zKOQLR6upDg/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 10:13:39 INFO - Downloaded 110497733 bytes. 10:13:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:13:39 INFO - mkdir: /builds/slave/test/properties 10:13:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:13:39 INFO - Writing to file /builds/slave/test/properties/build_url 10:13:39 INFO - Contents: 10:13:39 INFO - build_url:https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 10:13:40 INFO - Running post-action listener: _resource_record_post_action 10:13:40 INFO - Running post-action listener: set_extra_try_arguments 10:13:40 INFO - ##### 10:13:40 INFO - ##### Finished download-and-extract step (success) 10:13:40 INFO - ##### 10:13:40 INFO - ##### 10:13:40 INFO - ##### Running populate-webroot step. 10:13:40 INFO - ##### 10:13:40 INFO - Running pre-action listener: _resource_record_pre_action 10:13:40 INFO - Running main action method: populate_webroot 10:13:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:13:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:13:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:13:40 INFO - '--tppagecycles', 10:13:40 INFO - '7'], 10:13:40 INFO - 'tests': ['tsvgm']}}, 10:13:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:13:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:13:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:13:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:13:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['damp', 'tps']}, 10:13:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'tests': ['damp', 'tps']}, 10:13:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['dromaeo_dom']}, 10:13:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:13:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other-e10s': {'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['a11yr', 10:13:40 INFO - 'ts_paint', 10:13:40 INFO - 'tpaint', 10:13:40 INFO - 'sessionrestore', 10:13:40 INFO - 'sessionrestore_no_auto_restore', 10:13:40 INFO - 'tabpaint']}, 10:13:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tsvgx', 10:13:40 INFO - 'tsvgr_opacity', 10:13:40 INFO - 'tart', 10:13:40 INFO - 'tscrollx', 10:13:40 INFO - 'cart']}, 10:13:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:13:40 INFO - 'tsvgr_opacity', 10:13:40 INFO - 'tart', 10:13:40 INFO - 'tscrollx', 10:13:40 INFO - 'cart']}, 10:13:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s'], 10:13:40 INFO - 'tests': ['tp5o']}, 10:13:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'tests': ['tp5o']}, 10:13:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--disable-e10s', 10:13:40 INFO - '--xperf_path', 10:13:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:40 INFO - 'tests': ['tp5n']}, 10:13:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:40 INFO - 'talos_options': ['--xperf_path', 10:13:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:40 INFO - 'tests': ['tp5n']}}, 10:13:40 INFO - 'talos.zip': {'path': '', 10:13:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:13:40 INFO - Running post-action listener: _resource_record_post_action 10:13:40 INFO - ##### 10:13:40 INFO - ##### Finished populate-webroot step (success) 10:13:40 INFO - ##### 10:13:40 INFO - ##### 10:13:40 INFO - ##### Running create-virtualenv step. 10:13:40 INFO - ##### 10:13:40 INFO - Running pre-action listener: _resource_record_pre_action 10:13:40 INFO - Running main action method: create_virtualenv 10:13:40 INFO - Creating virtualenv /builds/slave/test/build/venv 10:13:40 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 10:13:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:13:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:13:40 INFO - Using real prefix '/tools/python27' 10:13:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:13:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:13:43 INFO - Installing pip.................done. 10:13:43 INFO - Return code: 0 10:13:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:43 INFO - 'HOME': '/Users/cltbld', 10:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:43 INFO - 'LOGNAME': 'cltbld', 10:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:43 INFO - 'MOZ_NO_REMOTE': '1', 10:13:43 INFO - 'NO_EM_RESTART': '1', 10:13:43 INFO - 'PAGER': '/bin/cat', 10:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:43 INFO - 'PWD': '/builds/slave/test', 10:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:43 INFO - 'SHELL': '/bin/bash', 10:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:13:43 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:43 INFO - 'USER': 'cltbld', 10:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:43 INFO - 'XPC_FLAGS': '0x0', 10:13:43 INFO - 'XPC_SERVICE_NAME': '0', 10:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:13:44 INFO - Cleaning up... 10:13:44 INFO - Return code: 0 10:13:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:13:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:13:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:13:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:13:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:44 INFO - 'HOME': '/Users/cltbld', 10:13:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:44 INFO - 'LOGNAME': 'cltbld', 10:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:44 INFO - 'MOZ_NO_REMOTE': '1', 10:13:44 INFO - 'NO_EM_RESTART': '1', 10:13:44 INFO - 'PAGER': '/bin/cat', 10:13:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:44 INFO - 'PWD': '/builds/slave/test', 10:13:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:44 INFO - 'SHELL': '/bin/bash', 10:13:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:13:44 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:13:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:44 INFO - 'USER': 'cltbld', 10:13:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:44 INFO - 'XPC_FLAGS': '0x0', 10:13:44 INFO - 'XPC_SERVICE_NAME': '0', 10:13:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:44 INFO - Downloading/unpacking psutil>=3.1.1 10:13:44 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 10:13:44 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 10:13:44 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 10:13:44 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 10:13:44 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 10:13:44 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 10:13:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:13:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:47 INFO - Installing collected packages: psutil 10:13:47 INFO - Running setup.py install for psutil 10:13:47 INFO - building 'psutil._psutil_osx' extension 10:13:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:13:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:13:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:13:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:13:48 INFO - building 'psutil._psutil_posix' extension 10:13:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:13:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:13:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:13:48 INFO - ^ 10:13:48 INFO - 1 warning generated. 10:13:48 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 10:13:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:48 INFO - Successfully installed psutil 10:13:48 INFO - Cleaning up... 10:13:48 INFO - Return code: 0 10:13:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:48 INFO - 'HOME': '/Users/cltbld', 10:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:48 INFO - 'LOGNAME': 'cltbld', 10:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:48 INFO - 'MOZ_NO_REMOTE': '1', 10:13:48 INFO - 'NO_EM_RESTART': '1', 10:13:48 INFO - 'PAGER': '/bin/cat', 10:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:48 INFO - 'PWD': '/builds/slave/test', 10:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:48 INFO - 'SHELL': '/bin/bash', 10:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:13:48 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:48 INFO - 'USER': 'cltbld', 10:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:48 INFO - 'XPC_FLAGS': '0x0', 10:13:48 INFO - 'XPC_SERVICE_NAME': '0', 10:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:13:51 INFO - Installing collected packages: mozsystemmonitor 10:13:51 INFO - Running setup.py install for mozsystemmonitor 10:13:51 INFO - Successfully installed mozsystemmonitor 10:13:51 INFO - Cleaning up... 10:13:51 INFO - Return code: 0 10:13:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:13:51 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')]} 10:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:51 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')]} 10:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:51 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 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:13:51 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 10:13:51 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 10:13:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:13:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:51 INFO - 'HOME': '/Users/cltbld', 10:13:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:51 INFO - 'LOGNAME': 'cltbld', 10:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:51 INFO - 'MOZ_NO_REMOTE': '1', 10:13:51 INFO - 'NO_EM_RESTART': '1', 10:13:51 INFO - 'PAGER': '/bin/cat', 10:13:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:51 INFO - 'PWD': '/builds/slave/test', 10:13:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:51 INFO - 'SHELL': '/bin/bash', 10:13:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:13:51 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:13:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:51 INFO - 'USER': 'cltbld', 10:13:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:51 INFO - 'XPC_FLAGS': '0x0', 10:13:51 INFO - 'XPC_SERVICE_NAME': '0', 10:13:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:53 INFO - Downloading blobuploader-1.2.4.tar.gz 10:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:13:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:13:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13: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 10:13:55 INFO - Downloading docopt-0.6.1.tar.gz 10:13:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:13:55 INFO - Installing collected packages: blobuploader, requests, docopt 10:13:55 INFO - Running setup.py install for blobuploader 10:13:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:13:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:13:55 INFO - Running setup.py install for requests 10:13:55 INFO - Running setup.py install for docopt 10:13:56 INFO - Successfully installed blobuploader requests docopt 10:13:56 INFO - Cleaning up... 10:13:56 INFO - Return code: 0 10:13:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:56 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 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:13:56 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 10:13:56 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 10:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:56 INFO - 'HOME': '/Users/cltbld', 10:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:56 INFO - 'LOGNAME': 'cltbld', 10:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:56 INFO - 'MOZ_NO_REMOTE': '1', 10:13:56 INFO - 'NO_EM_RESTART': '1', 10:13:56 INFO - 'PAGER': '/bin/cat', 10:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:56 INFO - 'PWD': '/builds/slave/test', 10:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:56 INFO - 'SHELL': '/bin/bash', 10:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:13:56 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:56 INFO - 'USER': 'cltbld', 10:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:56 INFO - 'XPC_FLAGS': '0x0', 10:13:56 INFO - 'XPC_SERVICE_NAME': '0', 10:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:56 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-UjDQu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:56 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-cV7Ktc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:56 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-FXfXKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:56 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-sRTOot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:56 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-VjuCmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:56 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-xltW6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ywbALd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-GujMHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-kzA5sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-9rfXyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-h_oUD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-L9W_nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-9kxJao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-L1xjmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Hik7ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:57 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-leJQL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:58 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Qzxvlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:13:58 INFO - Running setup.py install for manifestparser 10:13:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:13:58 INFO - Running setup.py install for mozcrash 10:13:58 INFO - Running setup.py install for mozdebug 10:13:58 INFO - Running setup.py install for mozdevice 10:13:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:13:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:13:58 INFO - Running setup.py install for mozfile 10:13:58 INFO - Running setup.py install for mozhttpd 10:13:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Running setup.py install for mozinfo 10:13:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Running setup.py install for mozInstall 10:13:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Running setup.py install for mozleak 10:13:59 INFO - Running setup.py install for mozlog 10:13:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Running setup.py install for moznetwork 10:13:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:13:59 INFO - Running setup.py install for mozprocess 10:13:59 INFO - Running setup.py install for mozprofile 10:14:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:14:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:14:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:14:00 INFO - Running setup.py install for mozrunner 10:14:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:14:00 INFO - Running setup.py install for mozscreenshot 10:14:00 INFO - Running setup.py install for moztest 10:14:00 INFO - Running setup.py install for mozversion 10:14:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:14:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:14:00 INFO - Cleaning up... 10:14:00 INFO - Return code: 0 10:14:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:00 INFO - 'HOME': '/Users/cltbld', 10:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:00 INFO - 'LOGNAME': 'cltbld', 10:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:00 INFO - 'MOZ_NO_REMOTE': '1', 10:14:00 INFO - 'NO_EM_RESTART': '1', 10:14:00 INFO - 'PAGER': '/bin/cat', 10:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:00 INFO - 'PWD': '/builds/slave/test', 10:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:00 INFO - 'SHELL': '/bin/bash', 10:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:14:00 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:00 INFO - 'USER': 'cltbld', 10:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:00 INFO - 'XPC_FLAGS': '0x0', 10:14:00 INFO - 'XPC_SERVICE_NAME': '0', 10:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-a_gUM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:14:01 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)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-UPbnNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:14:01 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)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-NFPtgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:14:01 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)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-B8qmZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-udlTYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-eeIAq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-rvdhQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-pB4Xqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-MgbM8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:14:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:14:01 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-dsbdoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Dp6PHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:14:02 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)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-7SA5EO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:14:02 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)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-L_Z1JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:14:02 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)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-VL1QmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-fMP8oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-MEqAHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:14:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:14:02 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-yBUKd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:14:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:14: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 10:14: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 10:14: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 10:14: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 10:14: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 10:14: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 10:14:05 INFO - Downloading blessings-1.6.tar.gz 10:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:14:05 INFO - Installing collected packages: blessings 10:14:05 INFO - Running setup.py install for blessings 10:14:05 INFO - Successfully installed blessings 10:14:05 INFO - Cleaning up... 10:14:05 INFO - Return code: 0 10:14:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:14:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:14:05 INFO - Reading from file tmpfile_stdout 10:14:05 INFO - Current package versions: 10:14:05 INFO - blessings == 1.6 10:14:05 INFO - blobuploader == 1.2.4 10:14:05 INFO - docopt == 0.6.1 10:14:05 INFO - manifestparser == 1.1 10:14:05 INFO - mozInstall == 1.12 10:14:05 INFO - mozcrash == 0.17 10:14:05 INFO - mozdebug == 0.1 10:14:05 INFO - mozdevice == 0.48 10:14:05 INFO - mozfile == 1.2 10:14:05 INFO - mozhttpd == 0.7 10:14:05 INFO - mozinfo == 0.9 10:14:05 INFO - mozleak == 0.1 10:14:05 INFO - mozlog == 3.2 10:14:05 INFO - moznetwork == 0.27 10:14:05 INFO - mozprocess == 0.22 10:14:05 INFO - mozprofile == 0.28 10:14:05 INFO - mozrunner == 6.11 10:14:05 INFO - mozscreenshot == 0.1 10:14:05 INFO - mozsystemmonitor == 0.3 10:14:05 INFO - moztest == 0.7 10:14:05 INFO - mozversion == 1.4 10:14:05 INFO - psutil == 3.1.1 10:14:05 INFO - requests == 1.2.3 10:14:05 INFO - wsgiref == 0.1.2 10:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:05 INFO - 'HOME': '/Users/cltbld', 10:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:05 INFO - 'LOGNAME': 'cltbld', 10:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:05 INFO - 'MOZ_NO_REMOTE': '1', 10:14:05 INFO - 'NO_EM_RESTART': '1', 10:14:05 INFO - 'PAGER': '/bin/cat', 10:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:05 INFO - 'PWD': '/builds/slave/test', 10:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:05 INFO - 'SHELL': '/bin/bash', 10:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:14:05 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:05 INFO - 'USER': 'cltbld', 10:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:05 INFO - 'XPC_FLAGS': '0x0', 10:14:05 INFO - 'XPC_SERVICE_NAME': '0', 10:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 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)) 10:14:06 INFO - Cleaning up... 10:14:06 INFO - Return code: 0 10:14:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:06 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 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:14:06 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 10:14:06 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 10:14:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:14:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:06 INFO - 'HOME': '/Users/cltbld', 10:14:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:06 INFO - 'LOGNAME': 'cltbld', 10:14:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:06 INFO - 'MOZ_NO_REMOTE': '1', 10:14:06 INFO - 'NO_EM_RESTART': '1', 10:14:06 INFO - 'PAGER': '/bin/cat', 10:14:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:06 INFO - 'PWD': '/builds/slave/test', 10:14:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:06 INFO - 'SHELL': '/bin/bash', 10:14:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:14:06 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:14:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:06 INFO - 'USER': 'cltbld', 10:14:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:06 INFO - 'XPC_FLAGS': '0x0', 10:14:06 INFO - 'XPC_SERVICE_NAME': '0', 10:14:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:06 INFO - Downloading/unpacking jsonschema 10:14:06 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 10:14:06 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 10:14:06 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 10:14:06 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 10:14:06 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 10:14:06 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 10:14:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:14:08 INFO - Downloading/unpacking functools32 (from jsonschema) 10:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:09 INFO - Downloading functools32-3.2.3-2.tar.gz 10:14:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:14:09 INFO - warning: no files found matching '*.txt' 10:14:09 INFO - no previously-included directories found matching 'build' 10:14:09 INFO - no previously-included directories found matching 'dist' 10:14:09 INFO - no previously-included directories found matching '.git*' 10:14:09 INFO - Installing collected packages: jsonschema, functools32 10:14:09 INFO - Running setup.py install for functools32 10:14:09 INFO - warning: no files found matching '*.txt' 10:14:09 INFO - no previously-included directories found matching 'build' 10:14:09 INFO - no previously-included directories found matching 'dist' 10:14:09 INFO - no previously-included directories found matching '.git*' 10:14:09 INFO - Successfully installed jsonschema functools32 10:14:09 INFO - Cleaning up... 10:14:09 INFO - Return code: 0 10:14:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:14:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:14:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:14:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110150a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110156570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f61d717a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, '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.MGHz1aIrpL/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.68OOIg8GmR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 10:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:09 INFO - 'HOME': '/Users/cltbld', 10:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:09 INFO - 'LOGNAME': 'cltbld', 10:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:09 INFO - 'MOZ_NO_REMOTE': '1', 10:14:09 INFO - 'NO_EM_RESTART': '1', 10:14:09 INFO - 'PAGER': '/bin/cat', 10:14:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:09 INFO - 'PWD': '/builds/slave/test', 10:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:09 INFO - 'SHELL': '/bin/bash', 10:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:14:09 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:09 INFO - 'USER': 'cltbld', 10:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:09 INFO - 'XPC_FLAGS': '0x0', 10:14:09 INFO - 'XPC_SERVICE_NAME': '0', 10:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:14:09 INFO - Downloading/unpacking flake8 10:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:14:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:14:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:14:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:14: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 10:14: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 10:14: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 10:14: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 10:14: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 10:14: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 10:14:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:14:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:14:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:14:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:14:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:14:13 INFO - no previously-included directories found matching 'docs/_build' 10:14:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:14:13 INFO - Running setup.py install for pep8 10:14:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:14:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:14:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:14:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:14:13 INFO - no previously-included directories found matching 'docs/_build' 10:14:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:14:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:14:13 INFO - Cleaning up... 10:14:13 INFO - Return code: 0 10:14:13 INFO - Running post-action listener: _resource_record_post_action 10:14:13 INFO - Running post-action listener: _start_resource_monitoring 10:14:13 INFO - Starting resource monitoring. 10:14:13 INFO - ##### 10:14:13 INFO - ##### Finished create-virtualenv step (success) 10:14:13 INFO - ##### 10:14:13 INFO - ##### 10:14:13 INFO - ##### Running install step. 10:14:13 INFO - ##### 10:14:13 INFO - Running pre-action listener: _resource_record_pre_action 10:14:13 INFO - Running main action method: install 10:14:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:14:14 INFO - Reading from file tmpfile_stdout 10:14:14 INFO - Detecting whether we're running mozinstall >=1.0... 10:14:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:14:14 INFO - Reading from file tmpfile_stdout 10:14:14 INFO - Output received: 10:14:14 INFO - Usage: mozinstall [options] installer 10:14:14 INFO - Options: 10:14:14 INFO - -h, --help show this help message and exit 10:14:14 INFO - -d DEST, --destination=DEST 10:14:14 INFO - Directory to install application into. [default: 10:14:14 INFO - "/builds/slave/test"] 10:14:14 INFO - --app=APP Application being installed. [default: firefox] 10:14:14 INFO - mkdir: /builds/slave/test/build/application 10:14:14 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'] 10:14:14 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 10:14:48 INFO - Reading from file tmpfile_stdout 10:14:48 INFO - Output received: 10:14:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:14:48 INFO - Running post-action listener: _resource_record_post_action 10:14:48 INFO - ##### 10:14:48 INFO - ##### Finished install step (success) 10:14:48 INFO - ##### 10:14:48 INFO - ##### 10:14:48 INFO - ##### Running run-tests step. 10:14:48 INFO - ##### 10:14:48 INFO - Running pre-action listener: _resource_record_pre_action 10:14:48 INFO - Running main action method: run_tests 10:14:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:48 INFO - 2016-05-26 10:14:48.039 screenresolution[1920:15445] starting screenresolution argv=screenresolution get 10:14:48 INFO - 2016-05-26 10:14:48.041 screenresolution[1920:15445] Display 0: 1600x1200x32@60 10:14:48 INFO - 2016-05-26 10:14:48.047 screenresolution[1921:15447] starting screenresolution argv=screenresolution list 10:14:48 INFO - Available Modes on Display 0 10:14:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:48 INFO - Intel Iris: 10:14:48 INFO - Chipset Model: Intel Iris 10:14:48 INFO - Type: GPU 10:14:48 INFO - Bus: Built-In 10:14:48 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:48 INFO - Vendor: Intel (0x8086) 10:14:48 INFO - Device ID: 0x0a2e 10:14:48 INFO - Revision ID: 0x0009 10:14:48 INFO - Displays: 10:14:48 INFO - Display: 10:14:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:48 INFO - Display Serial Number: 1600x1200 60 10:14:48 INFO - Main Display: Yes 10:14:48 INFO - Mirror: Off 10:14:48 INFO - Online: Yes 10:14:48 INFO - Rotation: Supported 10:14:48 INFO - Return code: 0 10:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:14:48 INFO - Python 2.7.3 10:14:48 INFO - Return code: 0 10:14:48 INFO - grabbing minidump binary from tooltool 10:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:14:48 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fff4d30>, '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 0x10fe18db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ffebb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:14:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:14:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:14:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:14:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:14:48 INFO - Return code: 0 10:14:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:14:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:14:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:14:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:14:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:14:49 INFO - Return code: 0 10:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0097 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MGHz1aIrpL/Render', 10:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:14:49 INFO - 'HOME': '/Users/cltbld', 10:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:14:49 INFO - 'LOGNAME': 'cltbld', 10:14:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:14:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:14:49 INFO - 'MOZ_NO_REMOTE': '1', 10:14:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:14:49 INFO - 'NO_EM_RESTART': '1', 10:14:49 INFO - 'PAGER': '/bin/cat', 10:14:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:14:49 INFO - 'PWD': '/builds/slave/test', 10:14:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:14:49 INFO - 'SHELL': '/bin/bash', 10:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.68OOIg8GmR/Listeners', 10:14:49 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 10:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:14:49 INFO - 'USER': 'cltbld', 10:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:14:49 INFO - 'XPC_FLAGS': '0x0', 10:14:49 INFO - 'XPC_SERVICE_NAME': '0', 10:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:14:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:14:49 INFO - mozversion application_buildid: 20160526082142 10:14:49 INFO - mozversion application_changeset: b0096c5c727749ad3e79cbdf20d2e96bd179c213 10:14:49 INFO - mozversion application_display_name: Nightly 10:14:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:14:49 INFO - mozversion application_name: Firefox 10:14:49 INFO - mozversion application_remotingname: firefox 10:14:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:14:49 INFO - mozversion application_vendor: Mozilla 10:14:49 INFO - mozversion application_version: 49.0a1 10:14:49 INFO - mozversion platform_buildid: 20160526082142 10:14:49 INFO - mozversion platform_changeset: b0096c5c727749ad3e79cbdf20d2e96bd179c213 10:14:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:14:49 INFO - mozversion platform_version: 49.0a1 10:14:49 INFO - using testdate: 1464282889 10:14:49 INFO - actual date: 1464282889 10:14:49 INFO - starting webserver on 'localhost:52107' 10:14:49 INFO - SUITE-START | Running 2 tests 10:14:49 INFO - TEST-START | dromaeo_css 10:14:49 INFO - operating with platform_type : mac_ 10:14:49 INFO - Initialising browser for dromaeo_css test... 10:14:49 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpGZFvlK/profile http://localhost:52107/getInfo.html) 10:14:51 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 10:14:51 INFO - PROCESS | 1939 | colorDepth:24 10:14:51 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 10:14:51 INFO - PROCESS | 1939 | __metrics 10:14:52 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:14:52 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:14:52 INFO - TEST-INFO | 1939: exit 0 10:14:52 INFO - Browser initialized. 10:14:52 INFO - Running cycle 1/1 for dromaeo_css test... 10:14:52 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpGZFvlK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:04 INFO - PROCESS | 1941 | RSS: Main: 241348608 10:15:04 INFO - PROCESS | 1941 | 10:15:55 INFO - PROCESS | 1941 | 1464282955964 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] 10:18:06 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52107/tests/dromaeo/cssquery-ext.html) 10:18:07 INFO - PROCESS | 1941 | RSS: Main: 380051456 10:18:07 INFO - PROCESS | 1941 | 10:21:10 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-ext.html (next: http://localhost:52107/tests/dromaeo/cssquery-jquery.html) 10:21:11 INFO - PROCESS | 1941 | RSS: Main: 374542336 10:21:11 INFO - PROCESS | 1941 | 10:24:13 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52107/tests/dromaeo/cssquery-mootools.html) 10:24:14 INFO - PROCESS | 1941 | RSS: Main: 374083584 10:24:14 INFO - PROCESS | 1941 | 10:27:16 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52107/tests/dromaeo/cssquery-prototype.html) 10:27:17 INFO - PROCESS | 1941 | RSS: Main: 372236288 10:27:17 INFO - PROCESS | 1941 | 10:30:20 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52107/tests/dromaeo/cssquery-yui.html) 10:30:21 INFO - PROCESS | 1941 | RSS: Main: 342581248 10:30:21 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:52107/tests/dromaeo/cssquery-yui.html (next: http://localhost:52107/tests/dromaeo/cssquery-dojo.html) 10:33:24 INFO - PROCESS | 1941 | RSS: Main: 392298496 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | __start_tp_report 10:33:24 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 10:33:24 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 10:33:24 INFO - PROCESS | 1941 | |i|pagename|runs| 10:33:24 INFO - PROCESS | 1941 | |0;dojo.html;2934.853257337133;3429.039868836727;3478;3480;3513.367593833109;5693.28833895763;5737.885242295151;5747.3677895431465;5805.448482394177;5808.6224395414265;5474.507294343508;5608.719564021803;5620.297462817148;5638.351589567201;5780.682062486561;5139;5174.146265866128;5210.687358758468;5239;5306.177542480922;5293.761780719877;5312.256284120207;5314.9468505315035;5325.520703136717;5361.035013697533;5108.667936584147;5112.054269960051;5154.6134039946965;5161.509656582619;5170.715610641413;5145.691258524501;5150.330457040598;5152.896942061176;5160.870978225536;5204.505571970657;5147.639665223436;5176.767045482962;5178.482151784829;5232.764525596357;5280.102382594968;5554.611177217596;5668.68822214778;5686.2607860977805;5689.886202275931;5712.742926568314;6264.185655864709;6299.464545513637;6303.779367722108;6318.33657465964;6331.936680633181;6246.656433896134;6295.338989406092;6308.842278943063;6312.715927783261;6330.556861019731;5775.653460792324;5889.322727886305;5939.643621382731;5947.82156535296;5992.161097446361;6324.71538780756;6329.145565348619;6329.525285603599;6331.081993110973;6336.6831658416895;6232.844178895473;6260.968695156495;6278.30938596754;6284.5600807943;6295;6124.356942520971;6202.658853762996;6207.6585787782105;6248.187735594434;6260.780872669435;6233.189685340877;6267.373262673787;6286.465650419674;6289.371062893765;6293.968530157412;6294.213223347082;6302.306746257908;6307.3377295383325;6308.085327627468;6317.873642527215;4873.293372461044;4875.488073752276;4918.01639672067;4945.381827271591;4973.8010479581135;4402.713823601455;4523.434570678665;4524.343970124313;4538.341940418621;4549.9990002199465;5713;5736;5740.799072032542;5753.33836608787;5758.568107391962;6198.2871969723155;6221.502279817604;6268.717907694165;6270.2789179244055;6314.8421289467215;5806.593538452268;5824.184614154022;5826.096954971986;5827.155062515996;5880.176775251468;5324.334458192726;5466.781328746805;5475.945240547623;5476.123820188751;5526.944730552724;6262.812115636538;6273.18448601688;6280.183576135165;6307.337729538424;6377.489800816016;5077.832098617265;5090.312807770903;6261.592996455216;6295.087212354275;6297.716602752796;6176.413240742122;6356.141920840627;6368.140301059298;6381.978883378637;6387.616742995435;6094.08588711679;6124.136831511639;6143.60399444226;6195.535334849995;6209.503239740719;6238.719257633326;6244.250689917239;6255.218097737783;6278.121063051085;6285.905711414241;6047.939520604914;6080.0575910733905;6088.817335479943;6093.390660933871;6107.84730381744;6110.984061328523;6113.1135985281;6117.418845209698;6118.984682626333;6147.047207682816;5811.418858114154;5912;5987.13186587951;5996.5502587305255;6037.516998640186;5139.537441630271;6169.136320914986;6178.598391104564;6197.380261973766;6211.440970312513;5002.824901128443;5881.970590147193;6060.636361818305;6063.211782468251;6077.392260773887 10:33:24 INFO - PROCESS | 1941 | |1;ext.html;496.40183578787565;500.7971771432569;509.72729589669535;513.9486051394861;523.4190049045559;24243.636345454834;30664.233394165156;41999.58000419999;42138.73583792491;42498.57501424985;41033.58966410335;43847.90380240496;43923.56076439234;43929.12141757171;44010;9427;9619.759506012335;9629.27780416468;9638.951805240964;9640.517974101302;9445.57494912727;9587.856182157273;9611.134997850191;9612.327137100407;9617.807643847118;9604.135627793497;9632.759181020461;9635;9650.372725772837;9653.613855445774;9580.664676736316;9609.711708648752;9623.422594644344;9625.855612165824;9647.421154730739;9496.857547136799;9588.95205523975;9590.42457452555;9598.088181622734;9598.856017159747;34347.4847877282;39164.60835391638;39874.60125398738;40290.19419611621;40306.395404068964;2616.55518561843;3123.9375212495625;3159.304952910356;3221.098092534094;3339.682730140633;28355.716442835514;28451.715482845113;28544.28639284014;28876.278093047633;29494.557581636294;2715.728427157288;2718.143784707811;2781.652293463317;2845.615841861343;2882.0489238551227;504190;504827.475862622;504984;506020;511323;60261;60874.39125608776;61088.69455652782;61116;61235.55057797727;34530;34896.65103348985;34924.47613285803;64376.678116610055;64482.355176448575;16606.667866642598;16762.74855877163;16800.99194048361;16808.991460512407;16879.07165105931;12733.490660373667;12831.422585983653;12849.807252891214;12849.935750321189;12858.035647326487;19470.5132371689;19796.90101549483;19856.70214946777;19956.90021549883;20015.599688006158;7960.800979975431;8009.799755006172;8012.3590112790835;8063.3399312428055;8095.3523718102415;890.3811850763657;973.6446197137993;1016.1972042086669;1023.1047833145997;1126.1610100475198;24932;28420.71579284222;29033.854830726134;29738.851305743334;30299;1021.97445063874;1022.6420752736632;1050.1231471721198;1054.4411461925192;1174.1663418972455;14295.857041429661;14371.425142994372;14620.976531642684;14698.826492873019;14842.886783491282;693138;693472;703243;704218;706419;57871;58182.7090864543;58197.41802582004;58397.70801145967;58512.707436462544;1306.4186437035428;1426.400911617144;1452.7167202395642;1459.3360021190545;1479.4747864507945;29083.418331632827;29251.414971700022;29737.10788676343;29780.70219297779;29871.402571948005;50249.24626130538;50756.68568171599;51188;51268.23097653613;51272;571784;578092;579594;579981.1000944969;581883;21528.493005489465;24604;25193;25343.239702808944;26213.344666383495;43948.780256098515;44011.33982990194;44472.555274446844;44701.32948005848;45243.321350180435;35314.82342588271;36892.81553592215;36966.630333697394;37683.43474847825;37732;29941;35982.4602630966;36035.45946810852;37042.62957370392;37103.62896371002;35135.64864351324;36214.63785362218;36327;36473;36973.6302636981;31044.379112418075;32655.34689306248;32722.345553089282;32835.67164328326;32981.67018329882;22142;23686.881565592063;23943.28170154898;24266.63600046036;24591.877040614683 10:33:24 INFO - PROCESS | 1941 | |2;jquery.html;386.3509304368661;387.62400471542605;391.0442056363288;392.3859160413954;412.8926479115431;13392.83935366899;19874;20779.376618701463;20914.372568822942;21016.05427755751;15962.281697323619;17132.31470741169;17955.192016359262;20220.69668954967;20278.98605069748;4953.777080031398;5128.9230661540105;5942.049272116462;5948.524118070544;6031.427014433643;5040.697558146505;5784.797532086378;5843.5325173985975;5879.147523609074;5883.264591926009;5725.4847063764255;5836;5840.707964601774;5870.677112758796;5914.290285165786;5773.30720313563;5818.3308919474375;5821;5847.356790753014;5909.556783241253;5032.723200223986;5160.845174644729;5197.298364720752;5208.166693329087;5208.270842082112;24602.384940376633;24895.253142405756;25751.227463175946;25796.581188034652;25928.740712592822;2269;2383.8688872112025;2438.7683170098812;2661.988444391119;2821.5767634854733;14541.418343266256;14842.406303747837;14903.627409314746;15144.621384465367;15600.29798659063;1464.699736554004;1697.7283634618402;1724.7326664366917;1726.2835923091902;1754.5175076853839;107965.46017269942;110740;112474.87525124726;112804;113219.43390282996;10642.787144257072;10928.726781830517;11165.553377864957;11186.272892261977;11301.60444384459;13989.86010139906;14104;14113.294335283195;14115.929420352833;14156;7561.168271490132;7612.695492180251;8371.288440482627;8532.36007299455;8645.740627781177;6927.75752848658;6950.687219075053;8414.200650938152;8461.492310461392;8466.66133354659;6661.16735408074;6778.966105169443;6790.551243465852;6796.402845331343;6814.420774234246;1670.866330693566;1776.2806063544285;1871.0551171658497;1881.74596429483;1883.096113865325;850.9191626795445;864.7665130414753;902.2917010147135;919.6689191890914;941.75985123793;20401.183952642026;20657.793422065886;21074.47313817136;21199.470013249793;21243.893780530998;949.1505102932837;967.4630580028152;980.9699815194018;984.950752462374;991.5884907763198;9431.575579098957;11156.88431156894;11358.829617555743;11361;11366.09071274296;227145;227747;444364;559380;561391;68809.65595171992;90646.54676726706;110078.44960775145;111379;115984.42007790075;936.541094863512;968.1141755293968;970.7670159161892;986.4475893499663;1038.9376637401629;17874.016929068504;17894.28422863123;18645.270945810622;18779.624407512045;18995.71506427377;37701.62298376981;47904.56286311416;48914.75542622264;51493.97012059663;52676.20985685295;73791.6310418466;74728.87906681513;76258;76650;78274;8077.7576672699915;8146.959265203636;8256.876146857681;8265.628046738031;8288.254057134887;11507.942460287644;12169.574064907685;12202.450889709808;12510.437030333838;12889.48442062307;10544.26190166681;10702.625408110678;10737.785244295226;10911;10968.616098436516;9852.802943941224;10384.221183411124;10428.27002109845;10603.522841472306;11136.276142050741;10693.679189624323;10884.891151088705;11347.602833900773;11419.486123124645;11456.656300311004;7769.53382797034;7993.560354180343;8121.025476942805;8144.144864789112;8328.531977032932;9848.606055757977;10948.397838118979;11189.328640281608;11232.382218978035;11255.268407553483 10:33:24 INFO - PROCESS | 1941 | |3;mootools.html;1369.671278893066;1585.239085239085;1590.3877007352169;1854.8608854335919;1982.940511784647;7741.922580774198;8204.835903281932;8206.056303525089;8221.506709597421;8232.83534329313;8381.20378564037;8655.134486551342;8656.307495400368;8745.825083498326;8758.167974042455;2311.653252012203;2392.7846493815555;2393.80849532037;2394.030417680842;2394.6288325309606;2353.6936999965014;2376.9168079117235;2384.1655420602738;2388.3312672451743;2391.6053851114543;2341.1571834139695;2368.100121953655;2375.5486457573093;2381.57131716291;2387.3673476528734;2304.06685292456;2311.1217737259904;2347.319277409549;2348.1429278313376;2359.2686267257204;2348.0607756897207;2355.093289083698;2385.7017872765905;2386.117136659447;2386.809055275574;8583.785405364853;8659.913400865973;8667.739967800977;8671.869921951176;8683.348748843862;5127.692338459704;5160.92258616121;5319.069162896478;5333.839984800462;5334.573234141299;3416.9829150854334;3432.7940323580665;3535.1869070114017;3545.3263879862743;3545.468179773029;990.1484723138095;1009.581023875091;1018.2312354172626;1021.4075836015168;1038.9247129221296;11258.43707814606;11408.258463199865;11437.542498300058;11451.427428628534;11456.54173833054;4742.857714268577;4775.761211939389;4791.520847915251;4854.514548545118;4893.486183950705;8059.556724380098;9939.105480506789;9984.750381240527;10009.499525023719;10033.949830250947;5371.4897084776885;5374.35507739066;5375.784968601212;5380.3274590676165;5381.0852155133725;3803.877856032477;4143.316352801814;4374.825006999748;4823.155947709207;4914.140025495524;3910.8631197907935;3956.3274243378696;3995.2608767377546;4035.253478106501;4069.409935559327;1069.7218723132041;1278.7506436244844;1288.9291088990196;1305.7845455499737;1306.5557710378328;743.4052757793744;793.2424534569473;895.3642913531465;929.1869614087673;943.1798921225486;5740.54075673945;5832.533397328203;5871.2073870027;6078.422549857756;6137.171481849893;905.5291248550717;910.4673765847053;914.6935776514943;952.6903756279098;964.5563041001078;3746.8875933722034;3904.511936007999;3923.4114882767813;3951.150502641946;4004.979975100106;10183.18534517237;10397.636082737068;10564.471776411303;10578.942105789514;10583.57665693379;10461.738456538647;10525.526351314209;10534.841977370346;10539;10571.94714026425;6496.870062598721;8152.633131508977;8154.347652187811;8167.550784706779;8167.959160204042;5703.344115426779;5757.9424205757405;5762.596618236683;5944.4352786485215;6028.668423236764;9220.907790922272;9408.858867116862;9666.178374838217;9670.226381889292;9674.75813104678;9703.6118555257;9795.559199835881;9799.85300220483;9803.656872009446;9815.165710914653;7386.519876208029;7528.134264559427;7531.661075251517;7541.396688264816;7544.132424771222;7963.243491868263;7967.880481792894;7981.441299109007;8062.435629505878;8072.556009419538;7279.017332660023;7335.596542190231;7341.045664063851;7420.962895185489;7481.438892083224;7145.606991615511;7174.67713952863;7267.454940879561;7285.708571657075;7311.524750891175;6559.540832141704;6788.660566971533;6865.725370985105;6868.244493105854;6954.165500140016;4770.5944994674455;4812.783424745825;4827.058723548875;4849.030193961293;4915.65590408668;4846;4850.975745121252;4864.708117512961;4969.925451118163;4991.126552853164 10:33:24 INFO - PROCESS | 1941 | |4;prototype.html;298.8625232393099;307.4849626874984;315.3283506131939;317.7839069432786;318.13943283418354;4337.306031035035;4698.90602187956;5214.6349755517085;5348.625596208267;5386.649867758593;4169.541350451455;4488.4838243602;4911.508849115086;5008.649394542384;5113.923291150636;3308.735301175906;3349.983250083746;3969.7618142911374;3993.743534902785;4087.2234275487563;3626.461476966121;3770.7556506352976;4002.9799851000703;4014.6988975826785;4052.696047796412;3771.849126034955;3886.978771039058;3915.647591716745;3992.680585553149;3999.8200080996426;3448.327576122657;3633.981830090833;3925.4896863407716;3965.7025723070883;3990.7206495545324;2851.7576006039512;3195.6005499312587;3447.9827600862086;3480.5207786690557;3494;4536.775070730884;4585.587297143273;4650.255959046536;5105.846824595268;5111.361079865017;3762;4358.191350589534;4587.72473651582;4608.446986361624;4624.653151013687;4833.661643684944;4843.782029808667;5283.86396924663;5522.1500797232975;5528.5073030282;3829.636184562462;3877.9806100969595;3893.9221215575812;3909.8045097745;3919.333713268751;4716.9056618867435;4729.858104256878;5449.891002179974;5870.7945221917025;5882.705864706748;3265.7877237979455;3396.43958746804;3564.625714300013;3874.302625527432;3884.9028774280305;3468.4277094279664;3560.9495198915865;3734.682551983112;3817.892811084754;3931.9017024574614;3846.7114966377635;4017.2568074906235;4364.869053928387;4570.702904311209;4585.449746030431;5594.104943209067;5600.747966341524;5616.354119276336;5621.8194179222;5627.8874422512135;1319.6869115230215;1341.4231880291568;1348.5549768576345;1351.9459221631025;1358.1986627889592;837.3636036612097;852.675983126408;853.6073406233203;888.2494292322976;893.4549924546021;4655;5114.948850511521;5119.155339368963;5189.506996835295;5219.807680774008;5589.636673616202;5591.539722522078;5614.494695477427;5669.716514174274;5692.036247494608;4821.516918786828;5134.717590532482;5194.4805519447755;5198.844034678966;5209.895802083937;2450.889709963056;2943.3524624582556;2993.1768763590185;2994.4160888626943;3026.7275945165043;5144.9742751286;5326.733663316819;5526.143447765603;5543.258300647047;5682.943170568324;4780.995990841962;5308.513616187473;5563.831595364938;5797.420257974169;5827.475527202572;4971.751412429437;5236.790528378822;5464.808731694372;5781.161731548902;5824.417558244227;5419.132938729785;5621.297337832771;5651.858703532444;5666.858328541654;5912.970435147969;5316.043112239834;5332.226827110125;5727.427257274239;5781.913271300849;5784.2769653793275;6037.75848966054;6042.788502402394;6079.848003799941;6100.23747031621;6101.359357267423;3741.3452645787424;3863.845446182121;3870.6259277957047;3875.2636998970106;3882.8835134946;5215.9739201305265;5436.374816895949;5815.82552523425;5839.124131380327;5862.12412028164;5468.91796623059;5735.4551317624755;5764.654120752768;5768.442520519352;5792.797252096325;4829.468758436639;5607;5651.660900345993;5668.26312579362;5701.315842098975;4429.844955426544;5142.04872098663;5388.56891448691;5541.861453463695;5543.667379957215;5637.971810141087;5666.688332141772;5667.20659107717;5682.261306030189;5690.3456102548735;5501.752421140978;5511.696856672921;5530.391656917816;5555.555555555465;5557.249771280825 10:33:24 INFO - PROCESS | 1941 | |5;yui.html;649.4285029174326;745.7129005332952;749.6251874062968;759.286270905349;770.310572038026;14728.999999999987;14986.700265994674;15459.768103478456;15464.613384665388;15468.84531154691;13793.448262069505;13902.374393152308;13929.442822287096;13935.930320348383;13979.930100349484;736.0504948616278;756.6027835386411;895.977600559988;897.0984160918284;930.4882314726907;825.6243409248777;922.5202894494859;927.6892241099206;929.4469790474691;930.9208717259007;969.3941286695815;972.3679608254656;974.2546951582045;974.6539978307705;980.1668581705529;737.0639288104104;945.0974319524837;969.3505351414569;972.9173020293283;975.4342481360794;703.4337358426465;704.4822055788991;704.6089420371692;704.6476761619191;707.9964600176966;12345.938270308681;14941.17823519706;14987.400503979828;15011;15028.474003409829;12599.05507086963;12628.936855315756;12767.042471814651;12776.04179686518;12866.742665146738;8911.242544383735;9809.460479673686;9892.109710126051;10309.84535231972;10327.27709060366;3412.488126780978;3448.793072415663;3470.6182319944787;3474.95507803799;3548.1221533637026;74593.88109178367;74857.6257118711;74985;75211.11972200738;75592.62203689001;8932.8660070099;9280.443173409481;9405.858912116324;9420;9559.187469065067;18100.461460775983;18803.623927521374;21878;21927.890360548416;22080.2271920482;7282.126144862545;7333.6333183341685;7455.776326710207;7578.810529736801;7618.657160427794;3654.3604869147794;3963.4451176835264;4021.2962731521866;4064.898377540526;4070.5929407059057;3348.1294863335706;3432.845521951518;3437.7765445245977;3441.5181874537584;3445.7932524048138;520.7136075158647;610.2128254551578;625.4027403829332;633.3096924352386;634.8698516804044;465.9190677627938;488.88266815963914;515.3506581707002;551.0631925726282;572.9570282228849;8181.468204566803;8409.747707568782;8575.27110195628;8792.516411597295;8801.603927823264;505.1992591742056;568.7156421789106;579.6319337220895;601.3715667127861;602.9186059881947;3691.3909204981414;3897.863574774926;3947.703922205846;3961.16815468749;3975.542812576533;85027.14972850317;85417.57291213628;88077.11922880818;88776.556117219;89266.55366723124;76557;77016.61491692618;77672;78497.60751196208;78896.99156799368;69363;69949.95075073878;69967.65016174887;70272;70331.2966870335;7512.624368781649;7638.159802421618;7738.4969976951315;7757.650905709143;7938.920610793818;43405.6361231056;43610.781946090065;43644.56355436405;43661.78169109134;44632.553674464136;58545.4145458557;58965.70517147387;60267.39732602618;61479.692601536706;61660;10025.197984161396;10228;10353.844692329772;10391.63629273002;10422.114120299859;11345.20583559176;11429.714257143638;11966;11973.940130299292;12251.571195008131;11065.557377704801;11233.157513186381;11268.605598804003;11285.943570282096;11588;11053.944730276618;11164.385958772345;11232.606858759904;11748.001419879405;11824.589271353185;9321.394109382869;11166.720831979266;11182.832257515982;11409.144314176305;12124.27254364741;7516.677910201765;9212.447253164832;9245.537723113952;9443.00848410907;9531.571079301586;9282.907170928203;9380.34337596389;9402.773987048002;9490.098440648128;9594.616215351307 10:33:24 INFO - PROCESS | 1941 | __end_tp_report 10:33:24 INFO - PROCESS | 1941 | __start_cc_report 10:33:24 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,1360 10:33:24 INFO - PROCESS | 1941 | __end_cc_report 10:33:24 INFO - PROCESS | 1941 | __startTimestamp1464284004385__endTimestamp 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | ------- Summary: start ------- 10:33:24 INFO - PROCESS | 1941 | Number of tests: 6 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | [#0] dojo.html Cycles:165 Average:5753.51 Median:5992.16 stddev:653.90 (10.9%) stddev-sans-first:617.39 10:33:24 INFO - PROCESS | 1941 | Values: 2934.9 3429.0 3478.0 3480.0 3513.4 5693.3 5737.9 5747.4 5805.4 5808.6 5474.5 5608.7 5620.3 5638.4 5780.7 5139.0 5174.1 5210.7 5239.0 5306.2 5293.8 5312.3 5314.9 5325.5 5361.0 5108.7 5112.1 5154.6 5161.5 5170.7 5145.7 5150.3 5152.9 5160.9 5204.5 5147.6 5176.8 5178.5 5232.8 5280.1 5554.6 5668.7 5686.3 5689.9 5712.7 6264.2 6299.5 6303.8 6318.3 6331.9 6246.7 6295.3 6308.8 6312.7 6330.6 5775.7 5889.3 5939.6 5947.8 5992.2 6324.7 6329.1 6329.5 6331.1 6336.7 6232.8 6261.0 6278.3 6284.6 6295.0 6124.4 6202.7 6207.7 6248.2 6260.8 6233.2 6267.4 6286.5 6289.4 6294.0 6294.2 6302.3 6307.3 6308.1 6317.9 4873.3 4875.5 4918.0 4945.4 4973.8 4402.7 4523.4 4524.3 4538.3 4550.0 5713.0 5736.0 5740.8 5753.3 5758.6 6198.3 6221.5 6268.7 6270.3 6314.8 5806.6 5824.2 5826.1 5827.2 5880.2 5324.3 5466.8 5475.9 5476.1 5526.9 6262.8 6273.2 6280.2 6307.3 6377.5 5077.8 5090.3 6261.6 6295.1 6297.7 6176.4 6356.1 6368.1 6382.0 6387.6 6094.1 6124.1 6143.6 6195.5 6209.5 6238.7 6244.3 6255.2 6278.1 6285.9 6047.9 6080.1 6088.8 6093.4 6107.8 6111.0 6113.1 6117.4 6119.0 6147.0 5811.4 5912.0 5987.1 5996.6 6037.5 5139.5 6169.1 6178.6 6197.4 6211.4 5002.8 5882.0 6060.6 6063.2 6077.4 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | [#1] ext.html Cycles:180 Average:71391.01 Median:37073.13 stddev:160871.90 (433.9%) stddev-sans-first:161235.13 10:33:24 INFO - PROCESS | 1941 | Values: 496.4 500.8 509.7 513.9 523.4 24243.6 30664.2 41999.6 42138.7 42498.6 41033.6 43847.9 43923.6 43929.1 44010.0 9427.0 9619.8 9629.3 9639.0 9640.5 9445.6 9587.9 9611.1 9612.3 9617.8 9604.1 9632.8 9635.0 9650.4 9653.6 9580.7 9609.7 9623.4 9625.9 9647.4 9496.9 9589.0 9590.4 9598.1 9598.9 34347.5 39164.6 39874.6 40290.2 40306.4 2616.6 3123.9 3159.3 3221.1 3339.7 28355.7 28451.7 28544.3 28876.3 29494.6 2715.7 2718.1 2781.7 2845.6 2882.0 504190.0 504827.5 504984.0 506020.0 511323.0 60261.0 60874.4 61088.7 61116.0 61235.6 34530.0 34896.7 34924.5 64376.7 64482.4 16606.7 16762.7 16801.0 16809.0 16879.1 12733.5 12831.4 12849.8 12849.9 12858.0 19470.5 19796.9 19856.7 19956.9 20015.6 7960.8 8009.8 8012.4 8063.3 8095.4 890.4 973.6 1016.2 1023.1 1126.2 24932.0 28420.7 29033.9 29738.9 30299.0 1022.0 1022.6 1050.1 1054.4 1174.2 14295.9 14371.4 14621.0 14698.8 14842.9 693138.0 693472.0 703243.0 704218.0 706419.0 57871.0 58182.7 58197.4 58397.7 58512.7 1306.4 1426.4 1452.7 1459.3 1479.5 29083.4 29251.4 29737.1 29780.7 29871.4 50249.2 50756.7 51188.0 51268.2 51272.0 571784.0 578092.0 579594.0 579981.1 581883.0 21528.5 24604.0 25193.0 25343.2 26213.3 43948.8 44011.3 44472.6 44701.3 45243.3 35314.8 36892.8 36966.6 37683.4 37732.0 29941.0 35982.5 36035.5 37042.6 37103.6 35135.6 36214.6 36327.0 36473.0 36973.6 31044.4 32655.3 32722.3 32835.7 32981.7 22142.0 23686.9 23943.3 24266.6 24591.9 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | [#2] jquery.html Cycles:180 Average:28439.19 Median:14295.36 stddev:73094.32 (511.3%) stddev-sans-first:73269.02 10:33:24 INFO - PROCESS | 1941 | Values: 386.4 387.6 391.0 392.4 412.9 13392.8 19874.0 20779.4 20914.4 21016.1 15962.3 17132.3 17955.2 20220.7 20279.0 4953.8 5128.9 5942.0 5948.5 6031.4 5040.7 5784.8 5843.5 5879.1 5883.3 5725.5 5836.0 5840.7 5870.7 5914.3 5773.3 5818.3 5821.0 5847.4 5909.6 5032.7 5160.8 5197.3 5208.2 5208.3 24602.4 24895.3 25751.2 25796.6 25928.7 2269.0 2383.9 2438.8 2662.0 2821.6 14541.4 14842.4 14903.6 15144.6 15600.3 1464.7 1697.7 1724.7 1726.3 1754.5 107965.5 110740.0 112474.9 112804.0 113219.4 10642.8 10928.7 11165.6 11186.3 11301.6 13989.9 14104.0 14113.3 14115.9 14156.0 7561.2 7612.7 8371.3 8532.4 8645.7 6927.8 6950.7 8414.2 8461.5 8466.7 6661.2 6779.0 6790.6 6796.4 6814.4 1670.9 1776.3 1871.1 1881.7 1883.1 850.9 864.8 902.3 919.7 941.8 20401.2 20657.8 21074.5 21199.5 21243.9 949.2 967.5 981.0 985.0 991.6 9431.6 11156.9 11358.8 11361.0 11366.1 227145.0 227747.0 444364.0 559380.0 561391.0 68809.7 90646.5 110078.4 111379.0 115984.4 936.5 968.1 970.8 986.4 1038.9 17874.0 17894.3 18645.3 18779.6 18995.7 37701.6 47904.6 48914.8 51494.0 52676.2 73791.6 74728.9 76258.0 76650.0 78274.0 8077.8 8147.0 8256.9 8265.6 8288.3 11507.9 12169.6 12202.5 12510.4 12889.5 10544.3 10702.6 10737.8 10911.0 10968.6 9852.8 10384.2 10428.3 10603.5 11136.3 10693.7 10884.9 11347.6 11419.5 11456.7 7769.5 7993.6 8121.0 8144.1 8328.5 9848.6 10948.4 11189.3 11232.4 11255.3 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | [#3] mootools.html Cycles:180 Average:5605.79 Median:5059.41 stddev:3099.14 (61.3%) stddev-sans-first:3091.48 10:33:24 INFO - PROCESS | 1941 | Values: 1369.7 1585.2 1590.4 1854.9 1982.9 7741.9 8204.8 8206.1 8221.5 8232.8 8381.2 8655.1 8656.3 8745.8 8758.2 2311.7 2392.8 2393.8 2394.0 2394.6 2353.7 2376.9 2384.2 2388.3 2391.6 2341.2 2368.1 2375.5 2381.6 2387.4 2304.1 2311.1 2347.3 2348.1 2359.3 2348.1 2355.1 2385.7 2386.1 2386.8 8583.8 8659.9 8667.7 8671.9 8683.3 5127.7 5160.9 5319.1 5333.8 5334.6 3417.0 3432.8 3535.2 3545.3 3545.5 990.1 1009.6 1018.2 1021.4 1038.9 11258.4 11408.3 11437.5 11451.4 11456.5 4742.9 4775.8 4791.5 4854.5 4893.5 8059.6 9939.1 9984.8 10009.5 10033.9 5371.5 5374.4 5375.8 5380.3 5381.1 3803.9 4143.3 4374.8 4823.2 4914.1 3910.9 3956.3 3995.3 4035.3 4069.4 1069.7 1278.8 1288.9 1305.8 1306.6 743.4 793.2 895.4 929.2 943.2 5740.5 5832.5 5871.2 6078.4 6137.2 905.5 910.5 914.7 952.7 964.6 3746.9 3904.5 3923.4 3951.2 4005.0 10183.2 10397.6 10564.5 10578.9 10583.6 10461.7 10525.5 10534.8 10539.0 10571.9 6496.9 8152.6 8154.3 8167.6 8168.0 5703.3 5757.9 5762.6 5944.4 6028.7 9220.9 9408.9 9666.2 9670.2 9674.8 9703.6 9795.6 9799.9 9803.7 9815.2 7386.5 7528.1 7531.7 7541.4 7544.1 7963.2 7967.9 7981.4 8062.4 8072.6 7279.0 7335.6 7341.0 7421.0 7481.4 7145.6 7174.7 7267.5 7285.7 7311.5 6559.5 6788.7 6865.7 6868.2 6954.2 4770.6 4812.8 4827.1 4849.0 4915.7 4846.0 4851.0 4864.7 4969.9 4991.1 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | [#4] prototype.html Cycles:180 Average:4475.82 Median:5112.64 stddev:1399.66 (27.4%) stddev-sans-first:1368.02 10:33:24 INFO - PROCESS | 1941 | Values: 298.9 307.5 315.3 317.8 318.1 4337.3 4698.9 5214.6 5348.6 5386.6 4169.5 4488.5 4911.5 5008.6 5113.9 3308.7 3350.0 3969.8 3993.7 4087.2 3626.5 3770.8 4003.0 4014.7 4052.7 3771.8 3887.0 3915.6 3992.7 3999.8 3448.3 3634.0 3925.5 3965.7 3990.7 2851.8 3195.6 3448.0 3480.5 3494.0 4536.8 4585.6 4650.3 5105.8 5111.4 3762.0 4358.2 4587.7 4608.4 4624.7 4833.7 4843.8 5283.9 5522.2 5528.5 3829.6 3878.0 3893.9 3909.8 3919.3 4716.9 4729.9 5449.9 5870.8 5882.7 3265.8 3396.4 3564.6 3874.3 3884.9 3468.4 3560.9 3734.7 3817.9 3931.9 3846.7 4017.3 4364.9 4570.7 4585.4 5594.1 5600.7 5616.4 5621.8 5627.9 1319.7 1341.4 1348.6 1351.9 1358.2 837.4 852.7 853.6 888.2 893.5 4655.0 5114.9 5119.2 5189.5 5219.8 5589.6 5591.5 5614.5 5669.7 5692.0 4821.5 5134.7 5194.5 5198.8 5209.9 2450.9 2943.4 2993.2 2994.4 3026.7 5145.0 5326.7 5526.1 5543.3 5682.9 4781.0 5308.5 5563.8 5797.4 5827.5 4971.8 5236.8 5464.8 5781.2 5824.4 5419.1 5621.3 5651.9 5666.9 5913.0 5316.0 5332.2 5727.4 5781.9 5784.3 6037.8 6042.8 6079.8 6100.2 6101.4 3741.3 3863.8 3870.6 3875.3 3882.9 5216.0 5436.4 5815.8 5839.1 5862.1 5468.9 5735.5 5764.7 5768.4 5792.8 4829.5 5607.0 5651.7 5668.3 5701.3 4429.8 5142.0 5388.6 5541.9 5543.7 5638.0 5666.7 5667.2 5682.3 5690.3 5501.8 5511.7 5530.4 5555.6 5557.2 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - PROCESS | 1941 | [#5] yui.html Cycles:180 Average:17487.91 Median:31044.95 stddev:24324.01 (78.4%) stddev-sans-first:24359.38 10:33:24 INFO - PROCESS | 1941 | Values: 649.4 745.7 749.6 759.3 770.3 14729.0 14986.7 15459.8 15464.6 15468.8 13793.4 13902.4 13929.4 13935.9 13979.9 736.1 756.6 896.0 897.1 930.5 825.6 922.5 927.7 929.4 930.9 969.4 972.4 974.3 974.7 980.2 737.1 945.1 969.4 972.9 975.4 703.4 704.5 704.6 704.6 708.0 12345.9 14941.2 14987.4 15011.0 15028.5 12599.1 12628.9 12767.0 12776.0 12866.7 8911.2 9809.5 9892.1 10309.8 10327.3 3412.5 3448.8 3470.6 3475.0 3548.1 74593.9 74857.6 74985.0 75211.1 75592.6 8932.9 9280.4 9405.9 9420.0 9559.2 18100.5 18803.6 21878.0 21927.9 22080.2 7282.1 7333.6 7455.8 7578.8 7618.7 3654.4 3963.4 4021.3 4064.9 4070.6 3348.1 3432.8 3437.8 3441.5 3445.8 520.7 610.2 625.4 633.3 634.9 465.9 488.9 515.4 551.1 573.0 8181.5 8409.7 8575.3 8792.5 8801.6 505.2 568.7 579.6 601.4 602.9 3691.4 3897.9 3947.7 3961.2 3975.5 85027.1 85417.6 88077.1 88776.6 89266.6 76557.0 77016.6 77672.0 78497.6 78897.0 69363.0 69950.0 69967.7 70272.0 70331.3 7512.6 7638.2 7738.5 7757.7 7938.9 43405.6 43610.8 43644.6 43661.8 44632.6 58545.4 58965.7 60267.4 61479.7 61660.0 10025.2 10228.0 10353.8 10391.6 10422.1 11345.2 11429.7 11966.0 11973.9 12251.6 11065.6 11233.2 11268.6 11285.9 11588.0 11053.9 11164.4 11232.6 11748.0 11824.6 9321.4 11166.7 11182.8 11409.1 12124.3 7516.7 9212.4 9245.5 9443.0 9531.6 9282.9 9380.3 9402.8 9490.1 9594.6 10:33:24 INFO - PROCESS | 1941 | -------- Summary: end -------- 10:33:24 INFO - PROCESS | 1941 | 10:33:24 INFO - TEST-INFO | 1941: exit 0 10:33:24 INFO - TEST-OK | dromaeo_css | took 1115274ms 10:33:24 INFO - TEST-START | kraken 10:33:24 INFO - operating with platform_type : mac_ 10:33:24 INFO - Initialising browser for kraken test... 10:33:25 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpQskX4y/profile http://localhost:52107/getInfo.html) 10:33:27 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 10:33:27 INFO - PROCESS | 1945 | colorDepth:24 10:33:27 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 10:33:27 INFO - PROCESS | 1945 | __metrics 10:33:27 INFO - PROCESS | 1945 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:33:27 ERROR - PROCESS | 1945 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:33:28 INFO - TEST-INFO | 1945: exit 0 10:33:28 INFO - Browser initialized. 10:33:28 INFO - Running cycle 1/1 for kraken test... 10:33:28 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpQskX4y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:33:40 INFO - PROCESS | 1946 | RSS: Main: 241467392 10:33:40 INFO - PROCESS | 1946 | 10:33:42 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-beat-detection) 10:33:43 INFO - PROCESS | 1946 | RSS: Main: 288964608 10:33:43 INFO - PROCESS | 1946 | 10:33:46 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-dft) 10:33:46 INFO - PROCESS | 1946 | RSS: Main: 297558016 10:33:46 INFO - PROCESS | 1946 | 10:33:50 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-fft) 10:33:51 INFO - PROCESS | 1946 | RSS: Main: 390156288 10:33:51 INFO - PROCESS | 1946 | 10:33:53 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52107/tests/kraken/driver.html?testName=audio-oscillator) 10:33:53 INFO - PROCESS | 1946 | RSS: Main: 286924800 10:33:53 INFO - PROCESS | 1946 | 10:33:55 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52107/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:33:56 INFO - PROCESS | 1946 | RSS: Main: 291389440 10:33:56 INFO - PROCESS | 1946 | 10:33:59 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52107/tests/kraken/driver.html?testName=imaging-darkroom) 10:33:59 INFO - PROCESS | 1946 | RSS: Main: 330063872 10:33:59 INFO - PROCESS | 1946 | 10:34:02 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52107/tests/kraken/driver.html?testName=imaging-desaturate) 10:34:03 INFO - PROCESS | 1946 | RSS: Main: 329641984 10:34:03 INFO - PROCESS | 1946 | 10:34:06 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52107/tests/kraken/driver.html?testName=json-parse-financial) 10:34:06 INFO - PROCESS | 1946 | RSS: Main: 329068544 10:34:06 INFO - PROCESS | 1946 | 10:34:08 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52107/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:34:09 INFO - PROCESS | 1946 | RSS: Main: 315490304 10:34:09 INFO - PROCESS | 1946 | 10:34:11 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:34:11 INFO - PROCESS | 1946 | RSS: Main: 321732608 10:34:11 INFO - PROCESS | 1946 | 10:34:14 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:34:14 INFO - PROCESS | 1946 | RSS: Main: 324644864 10:34:14 INFO - PROCESS | 1946 | 10:34:17 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:34:18 INFO - PROCESS | 1946 | RSS: Main: 329474048 10:34:18 INFO - PROCESS | 1946 | 10:34:21 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:34:21 INFO - PROCESS | 1946 | RSS: Main: 327155712 10:34:21 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:52107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52107/tests/kraken/driver.html?testName=ai-astar) 10:34:23 INFO - PROCESS | 1946 | RSS: Main: 374280192 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | __start_tp_report 10:34:23 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 10:34:23 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 10:34:23 INFO - PROCESS | 1946 | |i|pagename|runs| 10:34:23 INFO - PROCESS | 1946 | |0;ai-astar;111;105;111;108;111;111;110;110;111;111 10:34:23 INFO - PROCESS | 1946 | |1;audio-beat-detection;156;123;123;166;131;145;156;113;177;145 10:34:23 INFO - PROCESS | 1946 | |2;audio-dft;184;184;184;180;182;185;179;183;183;184 10:34:23 INFO - PROCESS | 1946 | |3;audio-fft;78;66;68;110;82;58;65;57;98;57 10:34:23 INFO - PROCESS | 1946 | |4;audio-oscillator;90;68;101;71;70;70;98;82;99;122 10:34:23 INFO - PROCESS | 1946 | |5;imaging-gaussian-blur;87;84;88;86;88;84;89;84;88;84 10:34:23 INFO - PROCESS | 1946 | |6;imaging-darkroom;94;94;97;93;93;94;117;151;111;103 10:34:23 INFO - PROCESS | 1946 | |7;imaging-desaturate;86;84;86;85;86;84;86;85;88;85 10:34:23 INFO - PROCESS | 1946 | |8;json-parse-financial;43;43;76;87;88;57;57;75;58;54 10:34:23 INFO - PROCESS | 1946 | |9;json-stringify-tinderbox;65;76;68;69;76;78;100;103;87;70 10:34:23 INFO - PROCESS | 1946 | |10;stanford-crypto-aes;86;86;85;85;85;87;86;82;86;86 10:34:23 INFO - PROCESS | 1946 | |11;stanford-crypto-ccm;141;143;143;137;137;137;139;156;183;151 10:34:23 INFO - PROCESS | 1946 | |12;stanford-crypto-pbkdf2;167;165;168;166;164;165;213;189;164;164 10:34:23 INFO - PROCESS | 1946 | |13;stanford-crypto-sha256-iterative;69;73;86;62;59;81;85;81;82;66 10:34:23 INFO - PROCESS | 1946 | __end_tp_report 10:34:23 INFO - PROCESS | 1946 | __start_cc_report 10:34:23 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,2040 10:34:23 INFO - PROCESS | 1946 | __end_cc_report 10:34:23 INFO - PROCESS | 1946 | __startTimestamp1464284063947__endTimestamp 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | ------- Summary: start ------- 10:34:23 INFO - PROCESS | 1946 | Number of tests: 14 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:1.97 (1.8%) stddev-sans-first:2.05 10:34:23 INFO - PROCESS | 1946 | Values: 111.0 105.0 111.0 108.0 111.0 111.0 110.0 110.0 111.0 111.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:150.50 stddev:20.74 (13.8%) stddev-sans-first:21.50 10:34:23 INFO - PROCESS | 1946 | Values: 156.0 123.0 123.0 166.0 131.0 145.0 156.0 113.0 177.0 145.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#2] audio-dft Cycles:10 Average:182.80 Median:184.00 stddev:1.93 (1.1%) stddev-sans-first:2.00 10:34:23 INFO - PROCESS | 1946 | Values: 184.0 184.0 184.0 180.0 182.0 185.0 179.0 183.0 183.0 184.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#3] audio-fft Cycles:10 Average:73.90 Median:67.00 stddev:18.16 (27.1%) stddev-sans-first:19.20 10:34:23 INFO - PROCESS | 1946 | Values: 78.0 66.0 68.0 110.0 82.0 58.0 65.0 57.0 98.0 57.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:76.50 stddev:18.00 (23.5%) stddev-sans-first:19.06 10:34:23 INFO - PROCESS | 1946 | Values: 90.0 68.0 101.0 71.0 70.0 70.0 98.0 82.0 99.0 122.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.50 stddev:2.04 (2.3%) stddev-sans-first:2.15 10:34:23 INFO - PROCESS | 1946 | Values: 87.0 84.0 88.0 86.0 88.0 84.0 89.0 84.0 88.0 84.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#6] imaging-darkroom Cycles:10 Average:104.70 Median:93.50 stddev:18.30 (19.6%) stddev-sans-first:19.00 10:34:23 INFO - PROCESS | 1946 | Values: 94.0 94.0 97.0 93.0 93.0 94.0 117.0 151.0 111.0 103.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 10:34:23 INFO - PROCESS | 1946 | Values: 86.0 84.0 86.0 85.0 86.0 84.0 86.0 85.0 88.0 85.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:66.50 stddev:16.62 (25.0%) stddev-sans-first:15.83 10:34:23 INFO - PROCESS | 1946 | Values: 43.0 43.0 76.0 87.0 88.0 57.0 57.0 75.0 58.0 54.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:73.00 stddev:13.32 (18.3%) stddev-sans-first:13.10 10:34:23 INFO - PROCESS | 1946 | Values: 65.0 76.0 68.0 69.0 76.0 78.0 100.0 103.0 87.0 70.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 10:34:23 INFO - PROCESS | 1946 | Values: 86.0 86.0 85.0 85.0 85.0 87.0 86.0 82.0 86.0 86.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:143.00 stddev:14.24 (10.0%) stddev-sans-first:14.95 10:34:23 INFO - PROCESS | 1946 | Values: 141.0 143.0 143.0 137.0 137.0 137.0 139.0 156.0 183.0 151.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:166.50 stddev:16.11 (9.7%) stddev-sans-first:16.96 10:34:23 INFO - PROCESS | 1946 | Values: 167.0 165.0 168.0 166.0 164.0 165.0 213.0 189.0 164.0 164.0 10:34:23 INFO - PROCESS | 1946 | 10:34:23 INFO - PROCESS | 1946 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:81.00 stddev:9.91 (12.2%) stddev-sans-first:10.32 10:34:23 INFO - PROCESS | 1946 | Values: 69.0 73.0 86.0 62.0 59.0 81.0 85.0 81.0 82.0 66.0 10:34:23 INFO - PROCESS | 1946 | -------- Summary: end -------- 10:34:23 INFO - PROCESS | 1946 | 10:34:24 INFO - TEST-INFO | 1946: exit 0 10:34:24 INFO - TEST-OK | kraken | took 59848ms 10:34:24 INFO - SUITE-END | took 1175s 10:34:25 INFO - Completed test suite (00:19:35) 10:34:25 INFO - javascript benchmark 10:34:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2934.853257337133, 3429.039868836727, 3478.0, 3480.0, 3513.367593833109, 5693.28833895763, 5737.885242295151, 5747.3677895431465, 5805.448482394177, 5808.6224395414265, 5474.507294343508, 5608.719564021803, 5620.297462817148, 5638.351589567201, 5780.682062486561, 5139.0, 5174.146265866128, 5210.687358758468, 5239.0, 5306.177542480922, 5293.761780719877, 5312.256284120207, 5314.9468505315035, 5325.520703136717, 5361.035013697533, 5108.667936584147, 5112.054269960051, 5154.6134039946965, 5161.509656582619, 5170.715610641413, 5145.691258524501, 5150.330457040598, 5152.896942061176, 5160.870978225536, 5204.505571970657, 5147.639665223436, 5176.767045482962, 5178.482151784829, 5232.764525596357, 5280.102382594968, 5554.611177217596, 5668.68822214778, 5686.2607860977805, 5689.886202275931, 5712.742926568314, 6264.185655864709, 6299.464545513637, 6303.779367722108, 6318.33657465964, 6331.936680633181, 6246.656433896134, 6295.338989406092, 6308.842278943063, 6312.715927783261, 6330.556861019731, 5775.653460792324, 5889.322727886305, 5939.643621382731, 5947.82156535296, 5992.161097446361, 6324.71538780756, 6329.145565348619, 6329.525285603599, 6331.081993110973, 6336.6831658416895, 6232.844178895473, 6260.968695156495, 6278.30938596754, 6284.5600807943, 6295.0, 6124.356942520971, 6202.658853762996, 6207.6585787782105, 6248.187735594434, 6260.780872669435, 6233.189685340877, 6267.373262673787, 6286.465650419674, 6289.371062893765, 6293.968530157412, 6294.213223347082, 6302.306746257908, 6307.3377295383325, 6308.085327627468, 6317.873642527215, 4873.293372461044, 4875.488073752276, 4918.01639672067, 4945.381827271591, 4973.8010479581135, 4402.713823601455, 4523.434570678665, 4524.343970124313, 4538.341940418621, 4549.9990002199465, 5713.0, 5736.0, 5740.799072032542, 5753.33836608787, 5758.568107391962, 6198.2871969723155, 6221.502279817604, 6268.717907694165, 6270.2789179244055, 6314.8421289467215, 5806.593538452268, 5824.184614154022, 5826.096954971986, 5827.155062515996, 5880.176775251468, 5324.334458192726, 5466.781328746805, 5475.945240547623, 5476.123820188751, 5526.944730552724, 6262.812115636538, 6273.18448601688, 6280.183576135165, 6307.337729538424, 6377.489800816016, 5077.832098617265, 5090.312807770903, 6261.592996455216, 6295.087212354275, 6297.716602752796, 6176.413240742122, 6356.141920840627, 6368.140301059298, 6381.978883378637, 6387.616742995435, 6094.08588711679, 6124.136831511639, 6143.60399444226, 6195.535334849995, 6209.503239740719, 6238.719257633326, 6244.250689917239, 6255.218097737783, 6278.121063051085, 6285.905711414241, 6047.939520604914, 6080.0575910733905, 6088.817335479943, 6093.390660933871, 6107.84730381744, 6110.984061328523, 6113.1135985281, 6117.418845209698, 6118.984682626333, 6147.047207682816, 5811.418858114154, 5912.0, 5987.13186587951, 5996.5502587305255, 6037.516998640186, 5139.537441630271, 6169.136320914986, 6178.598391104564, 6197.380261973766, 6211.440970312513, 5002.824901128443, 5881.970590147193, 6060.636361818305, 6063.211782468251, 6077.392260773887], "value": 5712.089017929815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.40183578787565, 500.7971771432569, 509.72729589669535, 513.9486051394861, 523.4190049045559, 24243.636345454834, 30664.233394165156, 41999.58000419999, 42138.73583792491, 42498.57501424985, 41033.58966410335, 43847.90380240496, 43923.56076439234, 43929.12141757171, 44010.0, 9427.0, 9619.759506012335, 9629.27780416468, 9638.951805240964, 9640.517974101302, 9445.57494912727, 9587.856182157273, 9611.134997850191, 9612.327137100407, 9617.807643847118, 9604.135627793497, 9632.759181020461, 9635.0, 9650.372725772837, 9653.613855445774, 9580.664676736316, 9609.711708648752, 9623.422594644344, 9625.855612165824, 9647.421154730739, 9496.857547136799, 9588.95205523975, 9590.42457452555, 9598.088181622734, 9598.856017159747, 34347.4847877282, 39164.60835391638, 39874.60125398738, 40290.19419611621, 40306.395404068964, 2616.55518561843, 3123.9375212495625, 3159.304952910356, 3221.098092534094, 3339.682730140633, 28355.716442835514, 28451.715482845113, 28544.28639284014, 28876.278093047633, 29494.557581636294, 2715.728427157288, 2718.143784707811, 2781.652293463317, 2845.615841861343, 2882.0489238551227, 504190.0, 504827.475862622, 504984.0, 506020.0, 511323.0, 60261.0, 60874.39125608776, 61088.69455652782, 61116.0, 61235.55057797727, 34530.0, 34896.65103348985, 34924.47613285803, 64376.678116610055, 64482.355176448575, 16606.667866642598, 16762.74855877163, 16800.99194048361, 16808.991460512407, 16879.07165105931, 12733.490660373667, 12831.422585983653, 12849.807252891214, 12849.935750321189, 12858.035647326487, 19470.5132371689, 19796.90101549483, 19856.70214946777, 19956.90021549883, 20015.599688006158, 7960.800979975431, 8009.799755006172, 8012.3590112790835, 8063.3399312428055, 8095.3523718102415, 890.3811850763657, 973.6446197137993, 1016.1972042086669, 1023.1047833145997, 1126.1610100475198, 24932.0, 28420.71579284222, 29033.854830726134, 29738.851305743334, 30299.0, 1021.97445063874, 1022.6420752736632, 1050.1231471721198, 1054.4411461925192, 1174.1663418972455, 14295.857041429661, 14371.425142994372, 14620.976531642684, 14698.826492873019, 14842.886783491282, 693138.0, 693472.0, 703243.0, 704218.0, 706419.0, 57871.0, 58182.7090864543, 58197.41802582004, 58397.70801145967, 58512.707436462544, 1306.4186437035428, 1426.400911617144, 1452.7167202395642, 1459.3360021190545, 1479.4747864507945, 29083.418331632827, 29251.414971700022, 29737.10788676343, 29780.70219297779, 29871.402571948005, 50249.24626130538, 50756.68568171599, 51188.0, 51268.23097653613, 51272.0, 571784.0, 578092.0, 579594.0, 579981.1000944969, 581883.0, 21528.493005489465, 24604.0, 25193.0, 25343.239702808944, 26213.344666383495, 43948.780256098515, 44011.33982990194, 44472.555274446844, 44701.32948005848, 45243.321350180435, 35314.82342588271, 36892.81553592215, 36966.630333697394, 37683.43474847825, 37732.0, 29941.0, 35982.4602630966, 36035.45946810852, 37042.62957370392, 37103.62896371002, 35135.64864351324, 36214.63785362218, 36327.0, 36473.0, 36973.6302636981, 31044.379112418075, 32655.34689306248, 32722.345553089282, 32835.67164328326, 32981.67018329882, 22142.0, 23686.881565592063, 23943.28170154898, 24266.63600046036, 24591.877040614683], "value": 19500.420782311954, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.3509304368661, 387.62400471542605, 391.0442056363288, 392.3859160413954, 412.8926479115431, 13392.83935366899, 19874.0, 20779.376618701463, 20914.372568822942, 21016.05427755751, 15962.281697323619, 17132.31470741169, 17955.192016359262, 20220.69668954967, 20278.98605069748, 4953.777080031398, 5128.9230661540105, 5942.049272116462, 5948.524118070544, 6031.427014433643, 5040.697558146505, 5784.797532086378, 5843.5325173985975, 5879.147523609074, 5883.264591926009, 5725.4847063764255, 5836.0, 5840.707964601774, 5870.677112758796, 5914.290285165786, 5773.30720313563, 5818.3308919474375, 5821.0, 5847.356790753014, 5909.556783241253, 5032.723200223986, 5160.845174644729, 5197.298364720752, 5208.166693329087, 5208.270842082112, 24602.384940376633, 24895.253142405756, 25751.227463175946, 25796.581188034652, 25928.740712592822, 2269.0, 2383.8688872112025, 2438.7683170098812, 2661.988444391119, 2821.5767634854733, 14541.418343266256, 14842.406303747837, 14903.627409314746, 15144.621384465367, 15600.29798659063, 1464.699736554004, 1697.7283634618402, 1724.7326664366917, 1726.2835923091902, 1754.5175076853839, 107965.46017269942, 110740.0, 112474.87525124726, 112804.0, 113219.43390282996, 10642.787144257072, 10928.726781830517, 11165.553377864957, 11186.272892261977, 11301.60444384459, 13989.86010139906, 14104.0, 14113.294335283195, 14115.929420352833, 14156.0, 7561.168271490132, 7612.695492180251, 8371.288440482627, 8532.36007299455, 8645.740627781177, 6927.75752848658, 6950.687219075053, 8414.200650938152, 8461.492310461392, 8466.66133354659, 6661.16735408074, 6778.966105169443, 6790.551243465852, 6796.402845331343, 6814.420774234246, 1670.866330693566, 1776.2806063544285, 1871.0551171658497, 1881.74596429483, 1883.096113865325, 850.9191626795445, 864.7665130414753, 902.2917010147135, 919.6689191890914, 941.75985123793, 20401.183952642026, 20657.793422065886, 21074.47313817136, 21199.470013249793, 21243.893780530998, 949.1505102932837, 967.4630580028152, 980.9699815194018, 984.950752462374, 991.5884907763198, 9431.575579098957, 11156.88431156894, 11358.829617555743, 11361.0, 11366.09071274296, 227145.0, 227747.0, 444364.0, 559380.0, 561391.0, 68809.65595171992, 90646.54676726706, 110078.44960775145, 111379.0, 115984.42007790075, 936.541094863512, 968.1141755293968, 970.7670159161892, 986.4475893499663, 1038.9376637401629, 17874.016929068504, 17894.28422863123, 18645.270945810622, 18779.624407512045, 18995.71506427377, 37701.62298376981, 47904.56286311416, 48914.75542622264, 51493.97012059663, 52676.20985685295, 73791.6310418466, 74728.87906681513, 76258.0, 76650.0, 78274.0, 8077.7576672699915, 8146.959265203636, 8256.876146857681, 8265.628046738031, 8288.254057134887, 11507.942460287644, 12169.574064907685, 12202.450889709808, 12510.437030333838, 12889.48442062307, 10544.26190166681, 10702.625408110678, 10737.785244295226, 10911.0, 10968.616098436516, 9852.802943941224, 10384.221183411124, 10428.27002109845, 10603.522841472306, 11136.276142050741, 10693.679189624323, 10884.891151088705, 11347.602833900773, 11419.486123124645, 11456.656300311004, 7769.53382797034, 7993.560354180343, 8121.025476942805, 8144.144864789112, 8328.531977032932, 9848.606055757977, 10948.397838118979, 11189.328640281608, 11232.382218978035, 11255.268407553483], "value": 9380.048457013645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.671278893066, 1585.239085239085, 1590.3877007352169, 1854.8608854335919, 1982.940511784647, 7741.922580774198, 8204.835903281932, 8206.056303525089, 8221.506709597421, 8232.83534329313, 8381.20378564037, 8655.134486551342, 8656.307495400368, 8745.825083498326, 8758.167974042455, 2311.653252012203, 2392.7846493815555, 2393.80849532037, 2394.030417680842, 2394.6288325309606, 2353.6936999965014, 2376.9168079117235, 2384.1655420602738, 2388.3312672451743, 2391.6053851114543, 2341.1571834139695, 2368.100121953655, 2375.5486457573093, 2381.57131716291, 2387.3673476528734, 2304.06685292456, 2311.1217737259904, 2347.319277409549, 2348.1429278313376, 2359.2686267257204, 2348.0607756897207, 2355.093289083698, 2385.7017872765905, 2386.117136659447, 2386.809055275574, 8583.785405364853, 8659.913400865973, 8667.739967800977, 8671.869921951176, 8683.348748843862, 5127.692338459704, 5160.92258616121, 5319.069162896478, 5333.839984800462, 5334.573234141299, 3416.9829150854334, 3432.7940323580665, 3535.1869070114017, 3545.3263879862743, 3545.468179773029, 990.1484723138095, 1009.581023875091, 1018.2312354172626, 1021.4075836015168, 1038.9247129221296, 11258.43707814606, 11408.258463199865, 11437.542498300058, 11451.427428628534, 11456.54173833054, 4742.857714268577, 4775.761211939389, 4791.520847915251, 4854.514548545118, 4893.486183950705, 8059.556724380098, 9939.105480506789, 9984.750381240527, 10009.499525023719, 10033.949830250947, 5371.4897084776885, 5374.35507739066, 5375.784968601212, 5380.3274590676165, 5381.0852155133725, 3803.877856032477, 4143.316352801814, 4374.825006999748, 4823.155947709207, 4914.140025495524, 3910.8631197907935, 3956.3274243378696, 3995.2608767377546, 4035.253478106501, 4069.409935559327, 1069.7218723132041, 1278.7506436244844, 1288.9291088990196, 1305.7845455499737, 1306.5557710378328, 743.4052757793744, 793.2424534569473, 895.3642913531465, 929.1869614087673, 943.1798921225486, 5740.54075673945, 5832.533397328203, 5871.2073870027, 6078.422549857756, 6137.171481849893, 905.5291248550717, 910.4673765847053, 914.6935776514943, 952.6903756279098, 964.5563041001078, 3746.8875933722034, 3904.511936007999, 3923.4114882767813, 3951.150502641946, 4004.979975100106, 10183.18534517237, 10397.636082737068, 10564.471776411303, 10578.942105789514, 10583.57665693379, 10461.738456538647, 10525.526351314209, 10534.841977370346, 10539.0, 10571.94714026425, 6496.870062598721, 8152.633131508977, 8154.347652187811, 8167.550784706779, 8167.959160204042, 5703.344115426779, 5757.9424205757405, 5762.596618236683, 5944.4352786485215, 6028.668423236764, 9220.907790922272, 9408.858867116862, 9666.178374838217, 9670.226381889292, 9674.75813104678, 9703.6118555257, 9795.559199835881, 9799.85300220483, 9803.656872009446, 9815.165710914653, 7386.519876208029, 7528.134264559427, 7531.661075251517, 7541.396688264816, 7544.132424771222, 7963.243491868263, 7967.880481792894, 7981.441299109007, 8062.435629505878, 8072.556009419538, 7279.017332660023, 7335.596542190231, 7341.045664063851, 7420.962895185489, 7481.438892083224, 7145.606991615511, 7174.67713952863, 7267.454940879561, 7285.708571657075, 7311.524750891175, 6559.540832141704, 6788.660566971533, 6865.725370985105, 6868.244493105854, 6954.165500140016, 4770.5944994674455, 4812.783424745825, 4827.058723548875, 4849.030193961293, 4915.65590408668, 4846.0, 4850.975745121252, 4864.708117512961, 4969.925451118163, 4991.126552853164], "value": 4524.145679975536, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.8625232393099, 307.4849626874984, 315.3283506131939, 317.7839069432786, 318.13943283418354, 4337.306031035035, 4698.90602187956, 5214.6349755517085, 5348.625596208267, 5386.649867758593, 4169.541350451455, 4488.4838243602, 4911.508849115086, 5008.649394542384, 5113.923291150636, 3308.735301175906, 3349.983250083746, 3969.7618142911374, 3993.743534902785, 4087.2234275487563, 3626.461476966121, 3770.7556506352976, 4002.9799851000703, 4014.6988975826785, 4052.696047796412, 3771.849126034955, 3886.978771039058, 3915.647591716745, 3992.680585553149, 3999.8200080996426, 3448.327576122657, 3633.981830090833, 3925.4896863407716, 3965.7025723070883, 3990.7206495545324, 2851.7576006039512, 3195.6005499312587, 3447.9827600862086, 3480.5207786690557, 3494.0, 4536.775070730884, 4585.587297143273, 4650.255959046536, 5105.846824595268, 5111.361079865017, 3762.0, 4358.191350589534, 4587.72473651582, 4608.446986361624, 4624.653151013687, 4833.661643684944, 4843.782029808667, 5283.86396924663, 5522.1500797232975, 5528.5073030282, 3829.636184562462, 3877.9806100969595, 3893.9221215575812, 3909.8045097745, 3919.333713268751, 4716.9056618867435, 4729.858104256878, 5449.891002179974, 5870.7945221917025, 5882.705864706748, 3265.7877237979455, 3396.43958746804, 3564.625714300013, 3874.302625527432, 3884.9028774280305, 3468.4277094279664, 3560.9495198915865, 3734.682551983112, 3817.892811084754, 3931.9017024574614, 3846.7114966377635, 4017.2568074906235, 4364.869053928387, 4570.702904311209, 4585.449746030431, 5594.104943209067, 5600.747966341524, 5616.354119276336, 5621.8194179222, 5627.8874422512135, 1319.6869115230215, 1341.4231880291568, 1348.5549768576345, 1351.9459221631025, 1358.1986627889592, 837.3636036612097, 852.675983126408, 853.6073406233203, 888.2494292322976, 893.4549924546021, 4655.0, 5114.948850511521, 5119.155339368963, 5189.506996835295, 5219.807680774008, 5589.636673616202, 5591.539722522078, 5614.494695477427, 5669.716514174274, 5692.036247494608, 4821.516918786828, 5134.717590532482, 5194.4805519447755, 5198.844034678966, 5209.895802083937, 2450.889709963056, 2943.3524624582556, 2993.1768763590185, 2994.4160888626943, 3026.7275945165043, 5144.9742751286, 5326.733663316819, 5526.143447765603, 5543.258300647047, 5682.943170568324, 4780.995990841962, 5308.513616187473, 5563.831595364938, 5797.420257974169, 5827.475527202572, 4971.751412429437, 5236.790528378822, 5464.808731694372, 5781.161731548902, 5824.417558244227, 5419.132938729785, 5621.297337832771, 5651.858703532444, 5666.858328541654, 5912.970435147969, 5316.043112239834, 5332.226827110125, 5727.427257274239, 5781.913271300849, 5784.2769653793275, 6037.75848966054, 6042.788502402394, 6079.848003799941, 6100.23747031621, 6101.359357267423, 3741.3452645787424, 3863.845446182121, 3870.6259277957047, 3875.2636998970106, 3882.8835134946, 5215.9739201305265, 5436.374816895949, 5815.82552523425, 5839.124131380327, 5862.12412028164, 5468.91796623059, 5735.4551317624755, 5764.654120752768, 5768.442520519352, 5792.797252096325, 4829.468758436639, 5607.0, 5651.660900345993, 5668.26312579362, 5701.315842098975, 4429.844955426544, 5142.04872098663, 5388.56891448691, 5541.861453463695, 5543.667379957215, 5637.971810141087, 5666.688332141772, 5667.20659107717, 5682.261306030189, 5690.3456102548735, 5501.752421140978, 5511.696856672921, 5530.391656917816, 5555.555555555465, 5557.249771280825], "value": 4034.566412274934, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.4285029174326, 745.7129005332952, 749.6251874062968, 759.286270905349, 770.310572038026, 14728.999999999987, 14986.700265994674, 15459.768103478456, 15464.613384665388, 15468.84531154691, 13793.448262069505, 13902.374393152308, 13929.442822287096, 13935.930320348383, 13979.930100349484, 736.0504948616278, 756.6027835386411, 895.977600559988, 897.0984160918284, 930.4882314726907, 825.6243409248777, 922.5202894494859, 927.6892241099206, 929.4469790474691, 930.9208717259007, 969.3941286695815, 972.3679608254656, 974.2546951582045, 974.6539978307705, 980.1668581705529, 737.0639288104104, 945.0974319524837, 969.3505351414569, 972.9173020293283, 975.4342481360794, 703.4337358426465, 704.4822055788991, 704.6089420371692, 704.6476761619191, 707.9964600176966, 12345.938270308681, 14941.17823519706, 14987.400503979828, 15011.0, 15028.474003409829, 12599.05507086963, 12628.936855315756, 12767.042471814651, 12776.04179686518, 12866.742665146738, 8911.242544383735, 9809.460479673686, 9892.109710126051, 10309.84535231972, 10327.27709060366, 3412.488126780978, 3448.793072415663, 3470.6182319944787, 3474.95507803799, 3548.1221533637026, 74593.88109178367, 74857.6257118711, 74985.0, 75211.11972200738, 75592.62203689001, 8932.8660070099, 9280.443173409481, 9405.858912116324, 9420.0, 9559.187469065067, 18100.461460775983, 18803.623927521374, 21878.0, 21927.890360548416, 22080.2271920482, 7282.126144862545, 7333.6333183341685, 7455.776326710207, 7578.810529736801, 7618.657160427794, 3654.3604869147794, 3963.4451176835264, 4021.2962731521866, 4064.898377540526, 4070.5929407059057, 3348.1294863335706, 3432.845521951518, 3437.7765445245977, 3441.5181874537584, 3445.7932524048138, 520.7136075158647, 610.2128254551578, 625.4027403829332, 633.3096924352386, 634.8698516804044, 465.9190677627938, 488.88266815963914, 515.3506581707002, 551.0631925726282, 572.9570282228849, 8181.468204566803, 8409.747707568782, 8575.27110195628, 8792.516411597295, 8801.603927823264, 505.1992591742056, 568.7156421789106, 579.6319337220895, 601.3715667127861, 602.9186059881947, 3691.3909204981414, 3897.863574774926, 3947.703922205846, 3961.16815468749, 3975.542812576533, 85027.14972850317, 85417.57291213628, 88077.11922880818, 88776.556117219, 89266.55366723124, 76557.0, 77016.61491692618, 77672.0, 78497.60751196208, 78896.99156799368, 69363.0, 69949.95075073878, 69967.65016174887, 70272.0, 70331.2966870335, 7512.624368781649, 7638.159802421618, 7738.4969976951315, 7757.650905709143, 7938.920610793818, 43405.6361231056, 43610.781946090065, 43644.56355436405, 43661.78169109134, 44632.553674464136, 58545.4145458557, 58965.70517147387, 60267.39732602618, 61479.692601536706, 61660.0, 10025.197984161396, 10228.0, 10353.844692329772, 10391.63629273002, 10422.114120299859, 11345.20583559176, 11429.714257143638, 11966.0, 11973.940130299292, 12251.571195008131, 11065.557377704801, 11233.157513186381, 11268.605598804003, 11285.943570282096, 11588.0, 11053.944730276618, 11164.385958772345, 11232.606858759904, 11748.001419879405, 11824.589271353185, 9321.394109382869, 11166.720831979266, 11182.832257515982, 11409.144314176305, 12124.27254364741, 7516.677910201765, 9212.447253164832, 9245.537723113952, 9443.00848410907, 9531.571079301586, 9282.907170928203, 9380.34337596389, 9402.773987048002, 9490.098440648128, 9594.616215351307], "value": 6712.101032089258, "unit": "score"}], "name": "dromaeo_css", "value": 7099.26914944154, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 108.0, 111.0, 111.0, 110.0, 110.0, 111.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 123.0, 166.0, 131.0, 145.0, 156.0, 113.0, 177.0, 145.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 184.0, 180.0, 182.0, 185.0, 179.0, 183.0, 183.0, 184.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 66.0, 68.0, 110.0, 82.0, 58.0, 65.0, 57.0, 98.0, 57.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 68.0, 101.0, 71.0, 70.0, 70.0, 98.0, 82.0, 99.0, 122.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 86.0, 88.0, 84.0, 89.0, 84.0, 88.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 97.0, 93.0, 93.0, 94.0, 117.0, 151.0, 111.0, 103.0], "value": 104.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 85.0, 86.0, 84.0, 86.0, 85.0, 88.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 76.0, 87.0, 88.0, 57.0, 57.0, 75.0, 58.0, 54.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 68.0, 69.0, 76.0, 78.0, 100.0, 103.0, 87.0, 70.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 85.0, 87.0, 86.0, 82.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 143.0, 143.0, 137.0, 137.0, 137.0, 139.0, 156.0, 183.0, 151.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 168.0, 166.0, 164.0, 165.0, 213.0, 189.0, 164.0, 164.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 86.0, 62.0, 59.0, 81.0, 85.0, 81.0, 82.0, 66.0], "value": 74.4, "unit": "score"}], "name": "kraken", "value": 1495.6000000000004, "alertThreshold": 2.0}]} 10:34:25 INFO - Return code: 0 10:34:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:34:25 ERROR - # TBPL SUCCESS # 10:34:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:34:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:34:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:34:25 INFO - 2016-05-26 10:34:25.250 screenresolution[1950:17445] starting screenresolution argv=screenresolution get 10:34:25 INFO - 2016-05-26 10:34:25.253 screenresolution[1950:17445] Display 0: 1600x1200x32@60 10:34:25 INFO - 2016-05-26 10:34:25.259 screenresolution[1951:17448] starting screenresolution argv=screenresolution list 10:34:25 INFO - Available Modes on Display 0 10:34:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:34:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:34:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:34:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:34:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:34:25 INFO - Intel Iris: 10:34:25 INFO - Chipset Model: Intel Iris 10:34:25 INFO - Type: GPU 10:34:25 INFO - Bus: Built-In 10:34:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:34:25 INFO - Vendor: Intel (0x8086) 10:34:25 INFO - Device ID: 0x0a2e 10:34:25 INFO - Revision ID: 0x0009 10:34:25 INFO - Displays: 10:34:25 INFO - Display: 10:34:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:34:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:34:25 INFO - Display Serial Number: 1600x1200 60 10:34:25 INFO - Main Display: Yes 10:34:25 INFO - Mirror: Off 10:34:25 INFO - Online: Yes 10:34:25 INFO - Rotation: Supported 10:34:25 INFO - Return code: 0 10:34:25 INFO - Running post-action listener: _resource_record_post_action 10:34:25 INFO - ##### 10:34:25 INFO - ##### Finished run-tests step (success) 10:34:25 INFO - ##### 10:34:25 INFO - Running post-run listener: _resource_record_post_run 10:34:25 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 602112; Write bytes: 694552064; Read time: 8; Write time: 1860 10:34:25 INFO - TinderboxPrint: CPU usage
53.6% 10:34:25 INFO - TinderboxPrint: I/O read bytes / time
602,112 / 8 10:34:25 INFO - TinderboxPrint: I/O write bytes / time
694,552,064 / 1,860 10:34:25 INFO - TinderboxPrint: CPU idle
2,247.6 (46.4%) 10:34:25 INFO - TinderboxPrint: CPU system
963.8 (19.9%) 10:34:25 INFO - TinderboxPrint: CPU user
1,634.3 (33.7%) 10:34:25 INFO - TinderboxPrint: Swap in / out
397,258,752 / 0 10:34:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397969920; Write bytes: 399521280; Read time: 29930; Write time: 779 10:34:25 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 602112; Write bytes: 294182912; Read time: 8; Write time: 1079 10:34:25 INFO - Running post-run listener: _upload_blobber_files 10:34:25 INFO - Blob upload gear active. 10:34:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:34:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:34:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:34:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:34:26 INFO - (blobuploader) - INFO - Open directory for files ... 10:34:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:34:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:34:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:27 INFO - (blobuploader) - INFO - Done attempting. 10:34:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:34:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:34:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:28 INFO - (blobuploader) - INFO - Done attempting. 10:34:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:34:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:34:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:34:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:34:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:34:30 INFO - (blobuploader) - INFO - Done attempting. 10:34:30 INFO - (blobuploader) - INFO - Iteration through files over. 10:34:30 INFO - Return code: 0 10:34:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:34:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:34:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b4664a3f66f14bc239f239fe5c6edc469bb4cc8571501151071c14b6dc5e1eec74eb3af986e974fcdd73b27a4b4577559c7816523035a0338df0da087b3c21a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43372351f24abc2f20537e746c5672e3ec55bfd51cbbd600e14f9e33d22d8878ae200aaf271b8f4762a0fc70901df6807d01159792ae3d701687b6dc88557e92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6c81e5cb98f0b0c3fc84ba228d366b363aeeae4dff5e518426e80791c4f8b3ad559c8145fbd666f7c7c55c57c4b0d399c829bc627de4d42bc993e2e9bcde03"} 10:34:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:34:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:34:30 INFO - Contents: 10:34:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b4664a3f66f14bc239f239fe5c6edc469bb4cc8571501151071c14b6dc5e1eec74eb3af986e974fcdd73b27a4b4577559c7816523035a0338df0da087b3c21a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43372351f24abc2f20537e746c5672e3ec55bfd51cbbd600e14f9e33d22d8878ae200aaf271b8f4762a0fc70901df6807d01159792ae3d701687b6dc88557e92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6c81e5cb98f0b0c3fc84ba228d366b363aeeae4dff5e518426e80791c4f8b3ad559c8145fbd666f7c7c55c57c4b0d399c829bc627de4d42bc993e2e9bcde03"} 10:34:30 INFO - Running post-run listener: copy_logs_to_upload_dir 10:34:30 INFO - Copying logs to upload dir... 10:34:30 INFO - mkdir: /builds/slave/test/build/upload/logs 10:34:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.988610 ========= master_lag: 2.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-26 10:34:34.056304) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 10:34:34.060892) ========= 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.MGHz1aIrpL/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.68OOIg8GmR/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b4664a3f66f14bc239f239fe5c6edc469bb4cc8571501151071c14b6dc5e1eec74eb3af986e974fcdd73b27a4b4577559c7816523035a0338df0da087b3c21a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43372351f24abc2f20537e746c5672e3ec55bfd51cbbd600e14f9e33d22d8878ae200aaf271b8f4762a0fc70901df6807d01159792ae3d701687b6dc88557e92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6c81e5cb98f0b0c3fc84ba228d366b363aeeae4dff5e518426e80791c4f8b3ad559c8145fbd666f7c7c55c57c4b0d399c829bc627de4d42bc993e2e9bcde03"} build_url:https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/LhdLYxqwRv2zKOQLR6upDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b4664a3f66f14bc239f239fe5c6edc469bb4cc8571501151071c14b6dc5e1eec74eb3af986e974fcdd73b27a4b4577559c7816523035a0338df0da087b3c21a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/43372351f24abc2f20537e746c5672e3ec55bfd51cbbd600e14f9e33d22d8878ae200aaf271b8f4762a0fc70901df6807d01159792ae3d701687b6dc88557e92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a6c81e5cb98f0b0c3fc84ba228d366b363aeeae4dff5e518426e80791c4f8b3ad559c8145fbd666f7c7c55c57c4b0d399c829bc627de4d42bc993e2e9bcde03"}' ========= master_lag: 0.32 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 10:34:34.396865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:34:34.397188) ========= 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.MGHz1aIrpL/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.68OOIg8GmR/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.005172 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 10:34:34.420416) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 10:34:34.420733) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 10:34:34.421115) ========= ========= Total master_lag: 2.92 =========