builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0122 starttime: 1463714531.5 results: success (0) buildid: 20160519184214 builduid: 4f59cb535d784b79bd21e16870009839 revision: 466d5c96f43964d4122959e5ab19131237bdc6fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:11.503300) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:11.503682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-05-19 20:22:11.504137) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-05-19 20:22:13.049303) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:13.053614) ========= 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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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-19 20:22:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-05-19 20:22:13 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.544579 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:13.693983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:13.694320) ========= 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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.027138 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:13.751350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 20:22:13.754787) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 466d5c96f43964d4122959e5ab19131237bdc6fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 466d5c96f43964d4122959e5ab19131237bdc6fa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SyKeVZuWL1/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.1zwvsDaAlw/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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-19 20:22:13,821 truncating revision to first 12 chars 2016-05-19 20:22:13,822 Setting DEBUG logging. 2016-05-19 20:22:13,822 attempt 1/10 2016-05-19 20:22:13,822 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/466d5c96f439?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 20:22:15,348 unpacking tar archive at: fx-team-466d5c96f439/testing/mozharness/ program finished with exit code 0 elapsedTime=1.766101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 20:22:15.540809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:15.541140) ========= script_repo_revision: 466d5c96f43964d4122959e5ab19131237bdc6fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:15.541552) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:15.541828) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 20:22:15.560010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-19 20:22:15.560317) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SyKeVZuWL1/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.1zwvsDaAlw/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22:15 INFO - MultiFileLogger online at 20160519 20:22:15 in /builds/slave/test 20:22:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:22:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:22:15 INFO - {'append_to_log': False, 20:22:15 INFO - 'base_work_dir': '/builds/slave/test', 20:22:15 INFO - 'blob_upload_branch': 'Fx-Team', 20:22:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:22:15 INFO - 'branch': 'Fx-Team', 20:22:15 INFO - 'buildbot_json_path': 'buildprops.json', 20:22:15 INFO - 'config_files': ('talos/mac_config.py',), 20:22:15 INFO - 'default_actions': ('clobber', 20:22:15 INFO - 'read-buildbot-config', 20:22:15 INFO - 'download-and-extract', 20:22:15 INFO - 'populate-webroot', 20:22:15 INFO - 'create-virtualenv', 20:22:15 INFO - 'install', 20:22:15 INFO - 'run-tests'), 20:22:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:22:15 INFO - 'download_minidump_stackwalk': True, 20:22:15 INFO - 'download_symbols': 'ondemand', 20:22:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:22:15 INFO - 'tooltool.py': '/tools/tooltool.py', 20:22:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:22:15 INFO - '/tools/misc-python/virtualenv.py')}, 20:22:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:22:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:22:15 INFO - 'installer_path': 'installer.exe', 20:22:15 INFO - 'log_level': 'info', 20:22:15 INFO - 'log_name': 'talos', 20:22:15 INFO - 'log_to_console': True, 20:22:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:22:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:22:15 INFO - 'opt_config_files': (), 20:22:15 INFO - 'pip_index': False, 20:22:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:22:15 INFO - 'cmd': ('bash', 20:22:15 INFO - '-c', 20:22:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:22:15 INFO - 'enabled': True, 20:22:15 INFO - 'halt_on_failure': False, 20:22:15 INFO - 'name': 'check_screen_resolution'},), 20:22:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:22:15 INFO - 'cmd': ('bash', 20:22:15 INFO - '-c', 20:22:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:22:15 INFO - 'enabled': True, 20:22:15 INFO - 'halt_on_failure': False, 20:22:15 INFO - 'name': 'check_screen_resolution'},), 20:22:15 INFO - 'run_cmd_checks_enabled': True, 20:22:15 INFO - 'sps_profile': False, 20:22:15 INFO - 'sps_profile_interval': 0, 20:22:15 INFO - 'suite': 'dromaeojs', 20:22:15 INFO - 'system_bits': '32', 20:22:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:22:15 INFO - 'title': 't-yosemite-r7-0122', 20:22:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:22:15 INFO - 'use_talos_json': True, 20:22:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:22:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:22:15 INFO - 'work_dir': 'build'} 20:22:15 INFO - ##### 20:22:15 INFO - ##### Running clobber step. 20:22:15 INFO - ##### 20:22:15 INFO - Running pre-action listener: _resource_record_pre_action 20:22:15 INFO - Running main action method: clobber 20:22:15 INFO - rmtree: /builds/slave/test/build 20:22:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:22:17 INFO - Running post-action listener: _resource_record_post_action 20:22:17 INFO - ##### 20:22:17 INFO - ##### Finished clobber step (success) 20:22:17 INFO - ##### 20:22:17 INFO - ##### 20:22:17 INFO - ##### Running read-buildbot-config step. 20:22:17 INFO - ##### 20:22:17 INFO - Running pre-action listener: _resource_record_pre_action 20:22:17 INFO - Running main action method: read_buildbot_config 20:22:17 INFO - Using buildbot properties: 20:22:17 INFO - { 20:22:17 INFO - "project": "", 20:22:17 INFO - "product": "firefox", 20:22:17 INFO - "script_repo_revision": "production", 20:22:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:22:17 INFO - "repository": "", 20:22:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:22:17 INFO - "buildid": "20160519184214", 20:22:17 INFO - "pgo_build": "False", 20:22:17 INFO - "basedir": "/builds/slave/test", 20:22:17 INFO - "buildnumber": 18, 20:22:17 INFO - "slavename": "t-yosemite-r7-0122", 20:22:17 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 20:22:17 INFO - "platform": "yosemite_r7", 20:22:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:22:17 INFO - "branch": "fx-team", 20:22:17 INFO - "repo_path": "integration/fx-team", 20:22:17 INFO - "revision": "466d5c96f43964d4122959e5ab19131237bdc6fa", 20:22:17 INFO - "stage_platform": "macosx64", 20:22:17 INFO - "builduid": "4f59cb535d784b79bd21e16870009839", 20:22:17 INFO - "slavebuilddir": "test" 20:22:17 INFO - } 20:22:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:22:17 INFO - Running post-action listener: _resource_record_post_action 20:22:17 INFO - ##### 20:22:17 INFO - ##### Finished read-buildbot-config step (success) 20:22:17 INFO - ##### 20:22:17 INFO - ##### 20:22:17 INFO - ##### Running download-and-extract step. 20:22:17 INFO - ##### 20:22:17 INFO - Running pre-action listener: _resource_record_pre_action 20:22:17 INFO - Running main action method: download_and_extract 20:22:17 INFO - mkdir: /builds/slave/test/build/tests 20:22: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')]} 20:22:17 INFO - https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:22:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:22:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:22:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:18 INFO - Downloaded 1199 bytes. 20:22:18 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:22:18 INFO - Using the following test package requirements: 20:22:18 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:22:18 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:22:18 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:22:18 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'jsshell-mac.zip'], 20:22:18 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:22:18 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:22:18 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:22:18 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:22:18 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:22:18 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:22:18 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:22:18 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:22:18 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')]} 20:22:18 INFO - https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:22:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:22:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:22:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:21 INFO - Downloaded 18630387 bytes. 20:22:21 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'] 20:22:21 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 20:22:24 INFO - Return code: 0 20:22:24 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 20:22:24 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')]} 20:22:24 INFO - https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:22:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:22:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:22:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:26 INFO - Downloaded 18630387 bytes. 20:22:26 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'] 20:22:26 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 20:22:29 INFO - Return code: 0 20:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:29 INFO - https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:22:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:22:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:22:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:31 INFO - Downloaded 10840359 bytes. 20:22:31 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'] 20:22:31 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 20:22:31 INFO - Return code: 0 20:22: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')]} 20:22:31 INFO - https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:22:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:22:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:22:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/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 20:22:36 INFO - Downloaded 110156853 bytes. 20:22:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:22:36 INFO - mkdir: /builds/slave/test/properties 20:22:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:36 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:36 INFO - Contents: 20:22:36 INFO - build_url:https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:22:36 INFO - Running post-action listener: _resource_record_post_action 20:22:36 INFO - Running post-action listener: set_extra_try_arguments 20:22:36 INFO - ##### 20:22:36 INFO - ##### Finished download-and-extract step (success) 20:22:36 INFO - ##### 20:22:36 INFO - ##### 20:22:36 INFO - ##### Running populate-webroot step. 20:22:36 INFO - ##### 20:22:36 INFO - Running pre-action listener: _resource_record_pre_action 20:22:36 INFO - Running main action method: populate_webroot 20:22:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:36 INFO - '--tppagecycles', 20:22:36 INFO - '7'], 20:22:36 INFO - 'tests': ['tsvgm']}}, 20:22:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['damp', 'tps']}, 20:22:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'tests': ['damp', 'tps']}, 20:22:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['dromaeo_dom']}, 20:22:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['a11yr', 20:22:36 INFO - 'ts_paint', 20:22:36 INFO - 'tpaint', 20:22:36 INFO - 'sessionrestore', 20:22:36 INFO - 'sessionrestore_no_auto_restore', 20:22:36 INFO - 'tabpaint']}, 20:22:36 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:36 INFO - 'ts_paint', 20:22:36 INFO - 'tpaint', 20:22:36 INFO - 'sessionrestore', 20:22:36 INFO - 'sessionrestore_no_auto_restore', 20:22:36 INFO - 'tabpaint']}, 20:22:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:36 INFO - 'ts_paint', 20:22:36 INFO - 'tpaint', 20:22:36 INFO - 'sessionrestore', 20:22:36 INFO - 'sessionrestore_no_auto_restore', 20:22:36 INFO - 'tabpaint']}, 20:22:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:36 INFO - 'ts_paint', 20:22:36 INFO - 'tpaint', 20:22:36 INFO - 'sessionrestore', 20:22:36 INFO - 'sessionrestore_no_auto_restore', 20:22:36 INFO - 'tabpaint']}, 20:22:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['a11yr', 20:22:36 INFO - 'ts_paint', 20:22:36 INFO - 'tpaint', 20:22:36 INFO - 'sessionrestore', 20:22:36 INFO - 'sessionrestore_no_auto_restore', 20:22:36 INFO - 'tabpaint']}, 20:22:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['a11yr', 20:22:36 INFO - 'ts_paint', 20:22:36 INFO - 'tpaint', 20:22:36 INFO - 'sessionrestore', 20:22:36 INFO - 'sessionrestore_no_auto_restore', 20:22:36 INFO - 'tabpaint']}, 20:22:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['tsvgx', 20:22:36 INFO - 'tsvgr_opacity', 20:22:36 INFO - 'tart', 20:22:36 INFO - 'tscrollx', 20:22:36 INFO - 'cart']}, 20:22:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:36 INFO - 'tsvgr_opacity', 20:22:36 INFO - 'tart', 20:22:36 INFO - 'tscrollx', 20:22:36 INFO - 'cart']}, 20:22:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'talos_options': ['--disable-e10s'], 20:22:36 INFO - 'tests': ['tp5o']}, 20:22:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'tests': ['tp5o']}, 20:22:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'talos_options': ['--disable-e10s', 20:22:36 INFO - '--xperf_path', 20:22:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:36 INFO - 'tests': ['tp5n']}, 20:22:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:36 INFO - 'talos_options': ['--xperf_path', 20:22:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:36 INFO - 'tests': ['tp5n']}}, 20:22:36 INFO - 'talos.zip': {'path': '', 20:22:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:36 INFO - Running post-action listener: _resource_record_post_action 20:22:36 INFO - ##### 20:22:36 INFO - ##### Finished populate-webroot step (success) 20:22:36 INFO - ##### 20:22:36 INFO - ##### 20:22:36 INFO - ##### Running create-virtualenv step. 20:22:36 INFO - ##### 20:22:36 INFO - Running pre-action listener: _resource_record_pre_action 20:22:36 INFO - Running main action method: create_virtualenv 20:22:36 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:36 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 20:22:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:36 INFO - Using real prefix '/tools/python27' 20:22:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:37 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:40 INFO - Installing pip.................done. 20:22:40 INFO - Return code: 0 20:22:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:40 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')]} 20:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:40 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')]} 20:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:40 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22:40 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 20:22:40 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 20:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:40 INFO - 'HOME': '/Users/cltbld', 20:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:40 INFO - 'LOGNAME': 'cltbld', 20:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:40 INFO - 'MOZ_NO_REMOTE': '1', 20:22:40 INFO - 'NO_EM_RESTART': '1', 20:22:40 INFO - 'PAGER': '/bin/cat', 20:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:40 INFO - 'PWD': '/builds/slave/test', 20:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:40 INFO - 'SHELL': '/bin/bash', 20:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:22:40 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:40 INFO - 'USER': 'cltbld', 20:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:40 INFO - 'XPC_FLAGS': '0x0', 20:22:40 INFO - 'XPC_SERVICE_NAME': '0', 20:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:40 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 20:22:40 INFO - Cleaning up... 20:22:40 INFO - Return code: 0 20:22:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:40 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')]} 20:22:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:40 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')]} 20:22:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:40 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22:40 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 20:22:40 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 20:22:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:22:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:40 INFO - 'HOME': '/Users/cltbld', 20:22:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:40 INFO - 'LOGNAME': 'cltbld', 20:22:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:40 INFO - 'MOZ_NO_REMOTE': '1', 20:22:40 INFO - 'NO_EM_RESTART': '1', 20:22:40 INFO - 'PAGER': '/bin/cat', 20:22:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:40 INFO - 'PWD': '/builds/slave/test', 20:22:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:40 INFO - 'SHELL': '/bin/bash', 20:22:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:22:40 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:22:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:40 INFO - 'USER': 'cltbld', 20:22:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:40 INFO - 'XPC_FLAGS': '0x0', 20:22:40 INFO - 'XPC_SERVICE_NAME': '0', 20:22:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:41 INFO - Downloading/unpacking psutil>=3.1.1 20:22:41 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 20:22:41 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 20:22:41 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 20:22:41 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 20:22:41 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 20:22:41 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 20:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:44 INFO - Installing collected packages: psutil 20:22:44 INFO - Running setup.py install for psutil 20:22:44 INFO - building 'psutil._psutil_osx' extension 20:22:44 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 20:22:44 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 20:22:44 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 20:22:44 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 20:22:44 INFO - building 'psutil._psutil_posix' extension 20:22:44 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 20:22:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:22:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:22:45 INFO - ^ 20:22:45 INFO - 1 warning generated. 20:22:45 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 20:22:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:45 INFO - Successfully installed psutil 20:22:45 INFO - Cleaning up... 20:22:45 INFO - Return code: 0 20:22:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:45 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22:45 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 20:22:45 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 20:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:45 INFO - 'HOME': '/Users/cltbld', 20:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:45 INFO - 'LOGNAME': 'cltbld', 20:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:45 INFO - 'MOZ_NO_REMOTE': '1', 20:22:45 INFO - 'NO_EM_RESTART': '1', 20:22:45 INFO - 'PAGER': '/bin/cat', 20:22:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:45 INFO - 'PWD': '/builds/slave/test', 20:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:45 INFO - 'SHELL': '/bin/bash', 20:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:22:45 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:45 INFO - 'USER': 'cltbld', 20:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:45 INFO - 'XPC_FLAGS': '0x0', 20:22:45 INFO - 'XPC_SERVICE_NAME': '0', 20:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:48 INFO - Installing collected packages: mozsystemmonitor 20:22:48 INFO - Running setup.py install for mozsystemmonitor 20:22:48 INFO - Successfully installed mozsystemmonitor 20:22:48 INFO - Cleaning up... 20:22:48 INFO - Return code: 0 20:22:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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', '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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:22: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 blobuploader==1.2.4 20:22:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:22:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:48 INFO - 'HOME': '/Users/cltbld', 20:22:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:48 INFO - 'LOGNAME': 'cltbld', 20:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:48 INFO - 'MOZ_NO_REMOTE': '1', 20:22:48 INFO - 'NO_EM_RESTART': '1', 20:22:48 INFO - 'PAGER': '/bin/cat', 20:22:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:48 INFO - 'PWD': '/builds/slave/test', 20:22:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:48 INFO - 'SHELL': '/bin/bash', 20:22:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:22:48 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:22:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:48 INFO - 'USER': 'cltbld', 20:22:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:48 INFO - 'XPC_FLAGS': '0x0', 20:22:48 INFO - 'XPC_SERVICE_NAME': '0', 20:22:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:48 INFO - Downloading/unpacking blobuploader==1.2.4 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:51 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:52 INFO - Downloading docopt-0.6.1.tar.gz 20:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:52 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:52 INFO - Running setup.py install for blobuploader 20:22:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:52 INFO - Running setup.py install for requests 20:22:52 INFO - Running setup.py install for docopt 20:22:53 INFO - Successfully installed blobuploader requests docopt 20:22:53 INFO - Cleaning up... 20:22:53 INFO - Return code: 0 20:22:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:53 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')]} 20:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:53 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')]} 20:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:53 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22:53 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 20:22:53 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 20:22:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:22:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:53 INFO - 'HOME': '/Users/cltbld', 20:22:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:53 INFO - 'LOGNAME': 'cltbld', 20:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:53 INFO - 'MOZ_NO_REMOTE': '1', 20:22:53 INFO - 'NO_EM_RESTART': '1', 20:22:53 INFO - 'PAGER': '/bin/cat', 20:22:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:53 INFO - 'PWD': '/builds/slave/test', 20:22:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:53 INFO - 'SHELL': '/bin/bash', 20:22:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:22:53 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:22:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:53 INFO - 'USER': 'cltbld', 20:22:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:53 INFO - 'XPC_FLAGS': '0x0', 20:22:53 INFO - 'XPC_SERVICE_NAME': '0', 20:22:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:53 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-mB9u2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:53 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-HVkwca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:53 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-EoyLAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:53 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-72mxvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:53 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BFobph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:53 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ncdkMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-2Ozg_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-8WZrRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BKAVwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Mk7Zhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-mkA_P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-CCvYF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Y8ocBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-GTBJdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-fheh_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:54 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-SI6mpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:55 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-lRF1uB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:55 INFO - Running setup.py install for manifestparser 20:22:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:55 INFO - Running setup.py install for mozcrash 20:22:55 INFO - Running setup.py install for mozdebug 20:22:55 INFO - Running setup.py install for mozdevice 20:22:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:55 INFO - Running setup.py install for mozfile 20:22:55 INFO - Running setup.py install for mozhttpd 20:22:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Running setup.py install for mozinfo 20:22:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Running setup.py install for mozInstall 20:22:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Running setup.py install for mozleak 20:22:56 INFO - Running setup.py install for mozlog 20:22:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Running setup.py install for moznetwork 20:22:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:56 INFO - Running setup.py install for mozprocess 20:22:56 INFO - Running setup.py install for mozprofile 20:22:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:57 INFO - Running setup.py install for mozrunner 20:22:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:57 INFO - Running setup.py install for mozscreenshot 20:22:57 INFO - Running setup.py install for moztest 20:22:57 INFO - Running setup.py install for mozversion 20:22:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:57 INFO - Cleaning up... 20:22:57 INFO - Return code: 0 20:22:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:57 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:22:57 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 20:22:57 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 20:22:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:22:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:57 INFO - 'HOME': '/Users/cltbld', 20:22:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:57 INFO - 'LOGNAME': 'cltbld', 20:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:57 INFO - 'MOZ_NO_REMOTE': '1', 20:22:57 INFO - 'NO_EM_RESTART': '1', 20:22:57 INFO - 'PAGER': '/bin/cat', 20:22:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:57 INFO - 'PWD': '/builds/slave/test', 20:22:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:57 INFO - 'SHELL': '/bin/bash', 20:22:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:22:57 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:22:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:57 INFO - 'USER': 'cltbld', 20:22:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:57 INFO - 'XPC_FLAGS': '0x0', 20:22:57 INFO - 'XPC_SERVICE_NAME': '0', 20:22:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-lgHr60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-UJIfle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-KbnH0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-HGHfaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-4Q_HaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-onMVcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-zx07d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-wzkoCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-gVMxLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:58 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)) 20:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-jxgb4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-h7DiuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-JxN37P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-mlwtbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-metVFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-23ak_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Vagrcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:59 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)) 20:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-kHF1gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:59 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)) 20:22:59 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)) 20:22:59 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)) 20:22:59 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)) 20:22:59 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)) 20:22:59 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)) 20:22:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:02 INFO - Downloading blessings-1.6.tar.gz 20:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:23:02 INFO - Installing collected packages: blessings 20:23:02 INFO - Running setup.py install for blessings 20:23:02 INFO - Successfully installed blessings 20:23:02 INFO - Cleaning up... 20:23:02 INFO - Return code: 0 20:23:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:23:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:02 INFO - Reading from file tmpfile_stdout 20:23:02 INFO - Current package versions: 20:23:02 INFO - blessings == 1.6 20:23:02 INFO - blobuploader == 1.2.4 20:23:02 INFO - docopt == 0.6.1 20:23:02 INFO - manifestparser == 1.1 20:23:02 INFO - mozInstall == 1.12 20:23:02 INFO - mozcrash == 0.17 20:23:02 INFO - mozdebug == 0.1 20:23:02 INFO - mozdevice == 0.48 20:23:02 INFO - mozfile == 1.2 20:23:02 INFO - mozhttpd == 0.7 20:23:02 INFO - mozinfo == 0.9 20:23:02 INFO - mozleak == 0.1 20:23:02 INFO - mozlog == 3.2 20:23:02 INFO - moznetwork == 0.27 20:23:02 INFO - mozprocess == 0.22 20:23:02 INFO - mozprofile == 0.28 20:23:02 INFO - mozrunner == 6.11 20:23:02 INFO - mozscreenshot == 0.1 20:23:02 INFO - mozsystemmonitor == 0.3 20:23:02 INFO - moztest == 0.7 20:23:02 INFO - mozversion == 1.4 20:23:02 INFO - psutil == 3.1.1 20:23:02 INFO - requests == 1.2.3 20:23:02 INFO - wsgiref == 0.1.2 20:23:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:02 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:23:02 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 20:23:02 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 20:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:02 INFO - 'HOME': '/Users/cltbld', 20:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:02 INFO - 'LOGNAME': 'cltbld', 20:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:02 INFO - 'MOZ_NO_REMOTE': '1', 20:23:02 INFO - 'NO_EM_RESTART': '1', 20:23:02 INFO - 'PAGER': '/bin/cat', 20:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:02 INFO - 'PWD': '/builds/slave/test', 20:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:02 INFO - 'SHELL': '/bin/bash', 20:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:23:02 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:02 INFO - 'USER': 'cltbld', 20:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:02 INFO - 'XPC_FLAGS': '0x0', 20:23:02 INFO - 'XPC_SERVICE_NAME': '0', 20:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 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)) 20:23:03 INFO - Cleaning up... 20:23:03 INFO - Return code: 0 20:23:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:03 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 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:23:03 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 20:23:03 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 20:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:03 INFO - 'HOME': '/Users/cltbld', 20:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:03 INFO - 'LOGNAME': 'cltbld', 20:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:03 INFO - 'MOZ_NO_REMOTE': '1', 20:23:03 INFO - 'NO_EM_RESTART': '1', 20:23:03 INFO - 'PAGER': '/bin/cat', 20:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:03 INFO - 'PWD': '/builds/slave/test', 20:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:03 INFO - 'SHELL': '/bin/bash', 20:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:23:03 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:03 INFO - 'USER': 'cltbld', 20:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:03 INFO - 'XPC_FLAGS': '0x0', 20:23:03 INFO - 'XPC_SERVICE_NAME': '0', 20:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:03 INFO - Downloading/unpacking jsonschema 20:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:23:05 INFO - Downloading/unpacking functools32 (from jsonschema) 20:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:06 INFO - Downloading functools32-3.2.3-2.tar.gz 20:23:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:23:06 INFO - warning: no files found matching '*.txt' 20:23:06 INFO - no previously-included directories found matching 'build' 20:23:06 INFO - no previously-included directories found matching 'dist' 20:23:06 INFO - no previously-included directories found matching '.git*' 20:23:06 INFO - Installing collected packages: jsonschema, functools32 20:23:06 INFO - Running setup.py install for functools32 20:23:06 INFO - warning: no files found matching '*.txt' 20:23:06 INFO - no previously-included directories found matching 'build' 20:23:06 INFO - no previously-included directories found matching 'dist' 20:23:06 INFO - no previously-included directories found matching '.git*' 20:23:06 INFO - Successfully installed jsonschema functools32 20:23:06 INFO - Cleaning up... 20:23:06 INFO - Return code: 0 20:23:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:23: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')]} 20:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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')]} 20:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a719a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a71f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd9557cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, '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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 20:23: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', 'flake8'] in /builds/slave/test/build 20:23: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 flake8 20:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:06 INFO - 'HOME': '/Users/cltbld', 20:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:06 INFO - 'LOGNAME': 'cltbld', 20:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:06 INFO - 'MOZ_NO_REMOTE': '1', 20:23:06 INFO - 'NO_EM_RESTART': '1', 20:23:06 INFO - 'PAGER': '/bin/cat', 20:23:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:06 INFO - 'PWD': '/builds/slave/test', 20:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:06 INFO - 'SHELL': '/bin/bash', 20:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:23:06 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:06 INFO - 'USER': 'cltbld', 20:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:06 INFO - 'XPC_FLAGS': '0x0', 20:23:06 INFO - 'XPC_SERVICE_NAME': '0', 20:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:06 INFO - Downloading/unpacking flake8 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:23:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:23:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:23:10 INFO - no previously-included directories found matching 'docs/_build' 20:23:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:23:10 INFO - Running setup.py install for pep8 20:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:23:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:23:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:23:10 INFO - no previously-included directories found matching 'docs/_build' 20:23:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:23:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:23:10 INFO - Cleaning up... 20:23:10 INFO - Return code: 0 20:23:10 INFO - Running post-action listener: _resource_record_post_action 20:23:10 INFO - Running post-action listener: _start_resource_monitoring 20:23:10 INFO - Starting resource monitoring. 20:23:10 INFO - ##### 20:23:10 INFO - ##### Finished create-virtualenv step (success) 20:23:10 INFO - ##### 20:23:10 INFO - ##### 20:23:10 INFO - ##### Running install step. 20:23:10 INFO - ##### 20:23:10 INFO - Running pre-action listener: _resource_record_pre_action 20:23:10 INFO - Running main action method: install 20:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:11 INFO - Reading from file tmpfile_stdout 20:23:11 INFO - Detecting whether we're running mozinstall >=1.0... 20:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:23:11 INFO - Reading from file tmpfile_stdout 20:23:11 INFO - Output received: 20:23:11 INFO - Usage: mozinstall [options] installer 20:23:11 INFO - Options: 20:23:11 INFO - -h, --help show this help message and exit 20:23:11 INFO - -d DEST, --destination=DEST 20:23:11 INFO - Directory to install application into. [default: 20:23:11 INFO - "/builds/slave/test"] 20:23:11 INFO - --app=APP Application being installed. [default: firefox] 20:23:11 INFO - mkdir: /builds/slave/test/build/application 20:23:11 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'] 20:23:11 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 20:23:44 INFO - Reading from file tmpfile_stdout 20:23:44 INFO - Output received: 20:23:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:23:44 INFO - Running post-action listener: _resource_record_post_action 20:23:44 INFO - ##### 20:23:44 INFO - ##### Finished install step (success) 20:23:44 INFO - ##### 20:23:44 INFO - ##### 20:23:44 INFO - ##### Running run-tests step. 20:23:44 INFO - ##### 20:23:44 INFO - Running pre-action listener: _resource_record_pre_action 20:23:44 INFO - Running main action method: run_tests 20:23:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:23:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:23:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:23:44 INFO - 2016-05-19 20:23:44.851 screenresolution[1641:7988] starting screenresolution argv=screenresolution get 20:23:44 INFO - 2016-05-19 20:23:44.854 screenresolution[1641:7988] Display 0: 1600x1200x32@60 20:23:44 INFO - 2016-05-19 20:23:44.860 screenresolution[1642:7991] starting screenresolution argv=screenresolution list 20:23:44 INFO - Available Modes on Display 0 20:23:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:23:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:23:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:23:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:23:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:23:45 INFO - Intel Iris: 20:23:45 INFO - Chipset Model: Intel Iris 20:23:45 INFO - Type: GPU 20:23:45 INFO - Bus: Built-In 20:23:45 INFO - VRAM (Dynamic, Max): 1536 MB 20:23:45 INFO - Vendor: Intel (0x8086) 20:23:45 INFO - Device ID: 0x0a2e 20:23:45 INFO - Revision ID: 0x0009 20:23:45 INFO - Displays: 20:23:45 INFO - Display: 20:23:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:23:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:23:45 INFO - Display Serial Number: 1600x1200 60 20:23:45 INFO - Main Display: Yes 20:23:45 INFO - Mirror: Off 20:23:45 INFO - Online: Yes 20:23:45 INFO - Rotation: Supported 20:23:45 INFO - Return code: 0 20:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:23:45 INFO - Python 2.7.3 20:23:45 INFO - Return code: 0 20:23:45 INFO - grabbing minidump binary from tooltool 20:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:45 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 0x10a5bdd30>, '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 0x10a3e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:23:45 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 20:23:45 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 20:23:45 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 20:23:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:23:45 INFO - Return code: 0 20:23:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:23:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:23:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:23:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:23:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:23:46 INFO - Return code: 0 20:23:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 20:23:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --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 20:23:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SyKeVZuWL1/Render', 20:23:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:46 INFO - 'HOME': '/Users/cltbld', 20:23:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:46 INFO - 'LOGNAME': 'cltbld', 20:23:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:23:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:46 INFO - 'MOZ_NO_REMOTE': '1', 20:23:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:23:46 INFO - 'NO_EM_RESTART': '1', 20:23:46 INFO - 'PAGER': '/bin/cat', 20:23:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:46 INFO - 'PWD': '/builds/slave/test', 20:23:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:23:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:46 INFO - 'SHELL': '/bin/bash', 20:23:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1zwvsDaAlw/Listeners', 20:23:46 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 20:23:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:46 INFO - 'USER': 'cltbld', 20:23:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:46 INFO - 'XPC_FLAGS': '0x0', 20:23:46 INFO - 'XPC_SERVICE_NAME': '0', 20:23:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 20:23:46 INFO - mozversion application_buildid: 20160519184214 20:23:46 INFO - mozversion application_changeset: 466d5c96f43964d4122959e5ab19131237bdc6fa 20:23:46 INFO - mozversion application_display_name: Nightly 20:23:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:23:46 INFO - mozversion application_name: Firefox 20:23:46 INFO - mozversion application_remotingname: firefox 20:23:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:23:46 INFO - mozversion application_vendor: Mozilla 20:23:46 INFO - mozversion application_version: 49.0a1 20:23:46 INFO - mozversion platform_buildid: 20160519184214 20:23:46 INFO - mozversion platform_changeset: 466d5c96f43964d4122959e5ab19131237bdc6fa 20:23:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:23:46 INFO - mozversion platform_version: 49.0a1 20:23:46 INFO - using testdate: 1463714626 20:23:46 INFO - actual date: 1463714626 20:23:46 INFO - starting webserver on 'localhost:49278' 20:23:46 INFO - SUITE-START | Running 2 tests 20:23:46 INFO - TEST-START | dromaeo_css 20:23:46 INFO - operating with platform_type : mac_ 20:23:46 INFO - Initialising browser for dromaeo_css test... 20:23:46 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpLpL7hs/profile http://localhost:49278/getInfo.html) 20:23:48 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 20:23:48 INFO - PROCESS | 1660 | colorDepth:24 20:23:48 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 20:23:48 INFO - PROCESS | 1660 | __metrics 20:23:49 INFO - PROCESS | 1660 | 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" 20:23:49 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:23:49 INFO - TEST-INFO | 1660: exit 0 20:23:49 INFO - Browser initialized. 20:23:49 INFO - Running cycle 1/1 for dromaeo_css test... 20:23:49 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpLpL7hs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:01 INFO - PROCESS | 1663 | RSS: Main: 238891008 20:24:01 INFO - PROCESS | 1663 | 20:24:53 INFO - PROCESS | 1663 | 1463714693111 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] 20:27:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 20:27:04 INFO - PROCESS | 1663 | RSS: Main: 344301568 20:27:04 INFO - PROCESS | 1663 | 20:30:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 20:30:08 INFO - PROCESS | 1663 | RSS: Main: 372957184 20:30:08 INFO - PROCESS | 1663 | 20:33:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 20:33:10 INFO - PROCESS | 1663 | RSS: Main: 368656384 20:33:10 INFO - PROCESS | 1663 | 20:36:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 20:36:13 INFO - PROCESS | 1663 | RSS: Main: 367624192 20:36:13 INFO - PROCESS | 1663 | 20:39:17 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 20:39:17 INFO - PROCESS | 1663 | RSS: Main: 340299776 20:39:17 INFO - PROCESS | 1663 | 20:42:20 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 20:42:20 INFO - PROCESS | 1663 | RSS: Main: 401248256 20:42:20 INFO - PROCESS | 1663 | 20:42:20 INFO - PROCESS | 1663 | __start_tp_report 20:42:20 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 20:42:20 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 20:42:20 INFO - PROCESS | 1663 | |i|pagename|runs| 20:42:20 INFO - PROCESS | 1663 | |0;dojo.html;3231.2245061185317;3432.0733401981465;3458.8097654628987;3461.2558299965503;3504.596971348296;5583.553315734742;5606.775728970846;5643.746031428585;5645.802396916109;5678.687672178028;5546.306711661053;5592.972035139829;5677.006523858318;5734.971325143379;5762.855928601787;4984.700917944917;5052.242163675442;5083.949160508385;5091.974540127294;5159.251908473269;5160.303359046518;5161.406438259611;5184.300119483878;5194.818181363653;5217.191335343009;5064.189729643258;5088.6437949343745;5131.923021154686;5139.614528910347;5183.429822719498;5090.872728181788;5103.464136265713;5136.948630513684;5139.640225184201;5155.71643559604;4517.59341659249;4921.8523444296725;5060.822871199527;5175.223716442526;5191.922121168185;5500.147477141009;5507.807226747084;5633.380328163898;5636.887262254773;5708.286464191976;6175.938240617581;6181.814545563595;6226.844328891769;6230.408111229426;6233.1273621693;6110.172019488931;6229.906551401733;6268.623882567061;6307.211598550181;6320.6207627542035;5643.802466913638;5722.97138514309;5783.71081445926;5849.795257165979;5852.619579727304;6132.816015519542;6270.561060725706;6281.937180628227;6299.149614801943;6308.148399965946;5482.890342193134;5843.094320380348;6213.875722485525;6217.658028808369;6246.437820596168;6079.240094988127;6130.938690613037;6205.472534834499;6241.687915604292;6285.182926219562;6207.565470417027;6244.094606282064;6271.122042913996;6272.905906411407;6279.968600157062;5689.175069614883;5693.349484679393;6290.21372328459;6290.842728931813;6317.6209427434505;4035.4753881995152;4831.0820944021225;4869.780859861315;4936.605071594264;5015.523525265094;3709.851605935786;3789.2232092420986;4424.203643344164;4475.865724028285;4497.3254011897825;4777.593904518086;5664.688442135722;5670.5463562914865;5685.488306052475;5702.429757024264;5134.460881607452;5938.485686150004;6302.621842689453;6307.369263073656;6344.206974128234;5519.917201241985;5543.473370029841;5566.832995010156;5605.439456054362;5670.659760414388;5330.946690533123;5394.838154855361;5406.83779486616;5413.079776438015;5454.400015998237;6342.746290148435;6349.619022858643;6363.331850155667;6367.235931688135;6370.140031095742;6092.969535152296;6118.162361256354;6160.537959653134;6166.075088736726;6177.413145751147;6285.151504546828;6388.744450221939;6389.808305750649;6408;6436.774712885027;6140.263168419819;6174.413430724074;6175.166352542349;6184.257889053342;6186.226721659793;6104.186323682738;6150.630962142286;6227.159333489921;6245.812625621239;6266.811995640138;6132.692058233554;6139.5228034422325;6140.04415716754;6144.907826382519;6146.385361463817;5969.164316995537;6080.118382834553;6119.654885716854;6123.801220575414;6168.074788781719;5831.125331200354;5923.259592550931;5948.256467941507;6002.459778619946;6038.9396106038375;6140.907886381798;6159.5072394209255;6159.81520554384;6172.043333283348;6180.783672571439;6030.44002560641;6044.486218671462;6046.334903160561;6057.061155520902;6082.72627731762 20:42:20 INFO - PROCESS | 1663 | |1;ext.html;499.9725015124168;523.4373048972353;526.0872386409582;535.6812696445613;549.5164255455198;31592.52611210834;44031.339529907076;45088.77455612725;45178.7741061295;45322.280663160236;46106.538934610646;46657.533424665744;46679.76660116695;46684.83287917795;46802;9703.07820757027;9895.257855660819;9900.35647682902;9902.059304366074;9918.206543476505;9578.56896439662;9629.037096290385;9661.71014869555;9744.123028927395;9750.268729845255;9690.127888490035;9721.125098741111;9774.364666296704;9784.951075244613;9823.263255255848;9667.516624168764;9686.70939871805;9713.70858874235;9751.609935602568;9777.608895644202;9681.951590242004;9781.90218097817;9787.510624468749;9796.216302695768;9800.215982721365;34896.302073958635;42899.57100428987;43283.350749738776;43292.78353608243;43294.35058474125;3132.2210521587635;3187.920301992469;3218.6298575663864;3227.241598224439;3437.1750779812915;28144;29946.850265748748;30643.540346894813;30965.38069238625;30976.0707178785;2615.0977912620115;2698.649175607178;2756.834589924611;2837.886484540616;2847.4589827932627;483746;494695;495374;496984;498015.5099224517;67268.32731672718;67327.3267267321;67763.66118169378;67948.32051679518;68009.65995170092;69698.65150674214;69790.95313570199;69916;71239.1504339831;71372.64313678502;17215.22531486086;17268;17490.737638935425;17503.82496175022;17509.64980700379;12588.118831681877;12606.117571770072;12613.117081804186;12630.052746044083;12637.810432843331;19573.804261957197;20347.491312717004;20464.181432742524;20465.795342046687;20525.97370131517;6766.357196066366;7380.77857664271;7946.284834364935;8000.239977202156;8098.271155596025;787.9803004924922;897.7486303834937;1032.9276950613385;1054.393723608928;1082.6968448834339;25045.872935717936;30036.24909377283;31049.068527944197;31226.21934451657;31648.208794779854;1010.6917390195914;1064.3347907557777;1106.5407855739938;1118.7594667146602;1141.4578075414279;11740.530378784924;15535.844641553665;15769.817263705268;15879.364825406854;15948.2823272953;701969;709368;709462;717373.9575625479;718487;63416.36583634197;63762.68118659377;63810;63987.36012639907;64225;1293.0560690695656;1410.4781230944616;1431.8711315981611;1459.4381163252012;1462.6782107936237;30909;31041.447927603076;31234.843825780725;31753;32020;49945.25082123699;51334;54910.45089549053;54915;55626.88746224972;585263;585554.0722296531;591159;591857.0407147937;592933;23724.762752372946;26301.07946221873;27080.458390832468;27219.319517012234;27425.451490970467;41446.378304324855;47363.76318118387;47547;48565;48940.02119957652;37400.62599373971;38901.638442654374;39065;39581.604183958945;40005.79982600527;37588.81205593955;37640;38139;38865.63970261027;39329.60670393259;37879.2424151521;38029.47882084685;39054.6094539051;39055;39139.804300978314;33458.16354591155;33620.83189584036;34152.82923585465;34248.315033699684;34462.48306275358;24996.500069998136;25103;25205.621915671647;25280.977521797846;25510.617340740275 20:42:20 INFO - PROCESS | 1663 | |2;jquery.html;386.3528589612399;390.50795997043724;392.5152436740627;392.82322954670406;415.95008598968127;13786.724265514684;20467.692984605223;21163.259285924996;21205.363839084832;21282.787172128275;16013.599660008476;18144.45566633;19053;19635;20416.591668166664;4860.927086093702;5897.587168898179;5904.88190236195;5905.055191169414;5908.61593996391;5725.427457254283;5877.67672777997;5947.464728174464;5949.464548190662;6108.358622344656;3872.0900588361715;4094.324436467994;5800.187973683687;6115.908261376083;6140.938590614103;6068.362821903681;6101;6155.353687862755;6155.415235552616;6170.382961703838;5741.62679425836;5867.911981320283;5913.704314784243;5916.881662366729;5917.14201440793;13446.462141514328;17364.56588585283;25353.87323063391;25508.74491255082;26227.082052128273;1903.7334773131772;2020.5150763816725;2121.7772133926023;2160.621891169055;2473.4434752180796;9588.856167157428;9788.314817962746;9860.556274967643;10019.949900250525;10261.333013354108;1468.537410715632;1649.9605248693367;1661.59290973711;1753.2198171813548;1813.5103522049105;89902.5504872478;122978.38510807478;126079.3696031514;126659.36670316682;128452;11128.721781955353;11329.943350283196;11377;11711.531538738525;11716.824247636292;14652.12087274767;14674.55976320712;14772.630684232765;14799;14805;8132;8641.956790216134;8730;8740.694075707319;8840.469571825712;6879.042011934816;7736.419768517271;8618.569071546397;8712.91287087121;8762.342824288204;4566.1552612766745;4593.402857628554;4600.033992861469;4602.286645569942;4660.53394660538;1769.973450398245;1918.7697476302653;1968.9015549222481;1969.4091772468203;1983.5537004173946;759.7530802489213;897.1432282170512;938.3666025432806;984.3060642247199;1032.2206733915846;19175;20232.291869784487;20378.89810550938;20472.488187795425;20879.582408351747;747.5327920049968;948.4641177734696;955.3742298794301;993.9055789700111;1001.0890090018048;9030.32272579546;10590.682279531626;10902.5093870776;10907.890921090846;10936.835947460795;518047;524605;525619;565558;617533.9123304355;121249;143633.15661498046;147924;148389;153754;958.7296831697888;1025.5795123999274;1031.2832581356035;1083.1984331594563;1086.576235268246;16449.753253700965;18305.633887321914;18364.62265330132;18372.35696750607;18427;54920;55776;55918.720406397704;56001.71999139978;56069;80183.59908200422;80555.19444805477;80999.59500202461;82638;83585;6859.691313890787;6976.511644185061;7428.6285685716575;7449.813754656177;8008.4783891060515;10222.846657300295;11834.76330473403;12292.754144916873;12429.813552796897;12753.482039483995;10849.294795838245;10943;10989.120870330195;11040.834387484034;11131.721706957067;10410.843837342294;10604.310719803188;10637.787244255227;10770.946145269538;10960.397178155277;11050.834237486604;11138.33170009802;11260.09919206478;11455.885441145481;11488.080953523531;7231.2768723127265;7399.852002960019;7538.623068846646;7609.619519023916;7669.118051424158;10555.525001374803;10782.946085269523;10873.238873279111;11027.779444410906;11149.83275250888 20:42:20 INFO - PROCESS | 1663 | |3;mootools.html;1422.957311280662;1605.4140238812836;1635.0271588404898;1879.436169149255;1999.4903847595062;7875.684972601096;8660.870086948693;8927;8931.106889311082;9022.503762293087;8602.655893764257;8848.601812918418;8849.336299777526;8853.5573221339;8877.778055548599;2111.788821117891;2322.907083716649;2368.6802281691926;2403.519296140779;2404.218628945591;2414.625733011377;2439.695038120235;2440.023990403835;2440.5973014452593;2447.339218411028;2382.65451509531;2406.639004149383;2411.3248290478696;2414.468816860288;2419.4314336130997;2085.2910010596393;2316.3745788636975;2352.3883790214495;2385.5705972924866;2387.916422925189;2377.6909001829827;2408.277516744987;2410.3251089694913;2411.8070554355786;2412.8793560322088;8676.783080422976;8722.215000649972;8729.825403491957;8737.213650771397;8776.517291548962;4500.144972455223;5152.252923326134;5207.947920520822;5364.6512976656395;5374.113271310229;3352.2625022495013;3429.159855835311;3503.5970863350785;3507.7719948203285;3511.157322242669;978.1441238915949;989.3470309595705;1006.8942761010135;1009.3237530854345;1021.1524434719165;11430;11746.941265293619;11840.585579504675;11892.821607675893;11924.22492537991;4728.361671174416;4763.21379482694;4833.371661684029;4839.491853355347;4892.168331383673;9142.222911052502;9187.770305742277;9257;9276;9284.48935308551;5210.921836172461;5226.451222621646;5286.022085914118;5300.867478312995;5306.389765176977;4446.3108218226225;4589.2886602576655;4805.735684537314;4924.285978533093;4924.630652701062;3752.9249415011545;3794.6395092466173;3833.0609000794984;3881.80590970456;3900.7659540427667;1009.8081364540714;1155.7226265696172;1156.392893730798;1171.0690001448868;1181;886.2732559301311;909.4770506958394;919.4207649181106;934.7427709730401;946.3848498476045;5270;5907.6406884258295;5914.852128696817;5970.164177015221;5978.28260608721;808.5108509351875;877.1710733356916;919.7056941778567;950.7718147644515;971.062924278075;3373.4433818420175;3876.8061596920043;3891.2606604745006;3967.761934283952;3994.6205110514456;10422.5830966762;10675.83986240207;10721.517531711092;10806.297590656583;10838;10692.144628429707;10696.51865666047;10731.40977246259;10881.347119172875;10890.346579205114;6434.774782882577;8243.793905152299;8328.8334233315;8338;8355.456895301786;5736.598438109292;5740.483356497935;5848.678322692293;5882.911756323573;5897.144913987534;9891.851622225528;9991.100800927952;10078.647247346307;10080.395176289447;10084.949575252076;9723.65967191145;9995.100440960057;10288.485575721334;10291.691249262534;10304.227182961158;6515.869682606416;6697.028930804909;6850.828729281808;6996.510244282851;7140.7143714252925;8232.794180145305;8280.917190828255;8399.496030238206;8403.327733781161;8416.4950102994;7319.560826350436;7416.295451932057;7464.290892365217;7550.848983020199;7618.504797188165;7245.601491917784;7303.853922921406;7323.780286591411;7376.151742549461;7437.553746775211;6910.654467276717;6978.860422791618;6989.685464154024;6997.405220556309;7011.894821577578;4653.790579423866;4773.511884034879;4887.975560122177;4914.164592019437;5088.898222035613;4285.892852678583;4547.431571053618;4915.26271059344;4955.72743499111;4979.676321039121 20:42:20 INFO - PROCESS | 1663 | |4;prototype.html;250.92346834215562;301.7962875059333;303.2479450961615;306.43615747025467;320.67771889251327;4376.409184760057;5060.468650791665;5146.819861304855;5431.511163995239;5454.118329176437;4259.382389553512;5131.769070391832;5315.973420132904;5329.786808527654;5370.737876598996;3844.1927195288954;4027.7180597358197;4034.152827906143;4034.899127521806;4049.3318602430563;3681.9631803681887;3779.3197224499577;4071.30787766079;4075.205334959684;4077.755334679914;3286.3568215892055;3562.895502394253;3859.614038596146;3986.7607943523335;4003.4195041718935;3639.344917914774;3730.6196707218232;3969.6824254059607;3990.980045099756;4027.1140349123148;3091.690830916913;3338.929863484655;3488;3491.4413693809124;3509.94735078974;4043.898902527431;4844.733539655317;5014.297998280207;5018.971110922253;5053.065182941167;4363.6072753452345;4578.381918441001;4601.884952876171;4621.237495813187;4640.327152562893;5471.425500322449;5521.861953451155;5538.944610553924;5539.889202215933;5572.136318870582;3289.490129030004;3741.5697194822674;3886.378179491268;3891.649751522348;3902.5902280260448;5076.593872490229;5535.501804837584;5605.607607467479;5798.623089499211;5798.710064496759;3944.2505923874373;3969.7419667721506;3984.8406063757125;3987.501562304712;4005.959940400617;3785.5268091488565;3989.740666856645;4013;4014.7791871447353;4041.4948131483566;3730.5896351401325;4230.809613567397;4391.5827996340295;4548.499665036843;4620.514845941195;4853.6602437829715;4996.975619997892;5267.499587539178;5483.616146869761;5623.437656234344;1086.4947799273375;1153.031453578981;1215.6474622359417;1314.5859054397833;1339.9129056611484;827.9917200828035;841.7516832534417;846.9667006252362;855.4653341661461;864.9740507784777;3956.723029387973;4327.653787696977;4343.066240758252;4383.824647014148;4388.125777615667;4092.6521245693853;4497.145542346943;4502.482214545304;4823.662343635982;4836.5888899443235;3793.7534060286544;3910.6089391061237;4292.849750258789;4315.438992930899;4385.868423947287;2309.411100169446;2502.5745623244093;2555.6327364859712;2570.1494923654936;2579.7678208960906;4371.759553224603;4457.5988161065015;4815.446223684251;5003.847576027152;5036.924446133311;4459.620932220798;4521.8973177472635;4648.976755116203;4905.7301848397965;4972.502749725071;4165.417441186484;4268.8719338420515;4500.604812508133;4697.718136911796;4873.952100298382;4224.4930608327195;4511.729296242104;4528.592426681615;4762.547557981986;4770.994273607605;4026.879193624196;4129.6254653574915;4196.874093777192;4743.667943244078;4752.310914917387;4331.653467722512;4643.442786865598;4697.906041879075;5016.673916195436;5051.318072060225;3317.822173128505;3385.559877215946;3410.0110967819055;3421.469672200813;3444.8622055117517;4773.522647735199;4773.56792962103;4789.95210047895;4873.415190177201;5440.700761458;5228.686278823283;5381.704006279692;5448.945510544844;5626.2685850839125;5630.662160270397;4230.661547076288;4750.904981900412;5169.431362550192;5308.522232999048;5602.215689803349;4706.306411623909;4939.678920870132;5365.731713414236;5448.839672989736;5479.835604931858;5473.17902314656;5521.420250873761;5530.834074977757;5534.806281780279;5571.66570005801;4651.534846515457;5251.36983561975;5427.375851777097;5435.945640543544;5546.362168350692 20:42:20 INFO - PROCESS | 1663 | |5;yui.html;649.8732747114313;681.9897701534478;705.2700455029046;737.9336858239842;739.2090463204373;11312.60405885793;14401.71196576069;17034.914825425854;17051.914740426313;17066.488005359828;15686.21568921555;16027.358905643789;16104.83895161048;16201.10893900835;16211.432599858977;722.3426681719636;735.4043224987751;923.1138107416871;935.050923312838;938.9905851209943;811.4968719393954;884.6151923913086;906.1074841281365;906.6871929184421;909.0954500272205;922.9815403691922;936.0826390137669;948.9620415183384;960.1982344742148;961.0341606685272;751.3876190904396;930.4138392812553;951.3150531617224;952.5284983932928;954.992482930508;696.4254490045682;699.6501749125438;715.3633266392915;716;720.0818955831305;14852.777208341884;14862.70274594513;14918.477853275082;14944.775828362475;14960.251987400587;11850.822237666442;11948.70128246792;11978.221415607957;12140.817887731691;12193.93903030488;10827.566897324097;10949.83575246372;11007.669769906915;11242.100631949424;11473.967342939175;3966.0481484443526;3969;3985.2826491231567;4112.814923328458;4119;85582;86885.56557217173;86946.56526717325;87391.56304218438;89218.55390723005;9146.817063658822;9192.862107068398;9297;9436.150746432853;9642.987486313976;16700;20599.38201853947;20607.896960515103;20609.587808243752;20630.17479300811;6995.8950615740805;6997.145547203036;7095.184053833876;7133;7135.750248741269;3925.0776067624083;3946.5461471931094;3966.6033396660105;3986.22268657609;4262.531121576624;2900.941981160365;3361.058903507022;3391.016605184518;3427.622961474236;3434.261633748756;516.4938360406823;519.4026869100564;598.297001023804;610.1001023490317;615.5291202230297;505.9696418214919;547.3951283831309;552.1193696054786;561.9466150715675;563.7294098832583;7464.385189030521;7676.808079797938;7738.845223095619;7916.841663166704;7983.640736166886;544.5589072851003;555.3363730342202;557.1225320120826;566.5043087298614;569.9658020518699;3413.709834664032;3513.9297214055578;3541.698955588804;3714.1271801126713;3785.73499855013;77489.2251077482;85288.29423411498;94417.6395590102;94897.525512372;97634;78707.60646196733;80236;81186;81577.59211203906;83601;62209.9999999991;62660;66794.66602666955;72520.54958900792;74620.62689686625;8153.586665343832;8170.468919520212;8205.835883282181;8255.298299644597;8325.209105135002;42618.45960025177;42802.50191243291;44042;44862.65412037644;46267.537324626326;61133;61783.691081544304;61887.69056154691;62392.68803655953;62499.43751406251;10978.917153320923;11047.72380690457;11090.778184436427;11195.944020280172;11199.72000699989;11518;11636.301821890713;11652.766944661229;11926.761464770829;12074.818877716665;10975.560977560808;11133.387663678575;11170.162237832032;11448.713782155513;11573.652790416301;11210.103191744478;11624.94187529057;11635.534578617098;12006.879931200576;12347.567835125728;10667.679969600924;10701.250912436055;10746.62386816489;10963.945180274048;11057.668269951915;8312.25189732927;8881.600327985385;8967.879660427992;9115;9247;7627.313541781266;9105.317101217302;9211.585478653342;9391.060893910555;9460.526973651153 20:42:20 INFO - PROCESS | 1663 | __end_tp_report 20:42:20 INFO - PROCESS | 1663 | __start_cc_report 20:42:20 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1230 20:42:20 INFO - PROCESS | 1663 | __end_cc_report 20:42:20 INFO - PROCESS | 1663 | __startTimestamp1463715740979__endTimestamp 20:42:20 INFO - PROCESS | 1663 | 20:42:20 INFO - PROCESS | 1663 | ------- Summary: start ------- 20:42:20 INFO - PROCESS | 1663 | Number of tests: 6 20:42:20 INFO - PROCESS | 1663 | 20:42:20 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5691.79 Median:5938.49 stddev:685.51 (11.5%) stddev-sans-first:659.87 20:42:20 INFO - PROCESS | 1663 | Values: 3231.2 3432.1 3458.8 3461.3 3504.6 5583.6 5606.8 5643.7 5645.8 5678.7 5546.3 5593.0 5677.0 5735.0 5762.9 4984.7 5052.2 5083.9 5092.0 5159.3 5160.3 5161.4 5184.3 5194.8 5217.2 5064.2 5088.6 5131.9 5139.6 5183.4 5090.9 5103.5 5136.9 5139.6 5155.7 4517.6 4921.9 5060.8 5175.2 5191.9 5500.1 5507.8 5633.4 5636.9 5708.3 6175.9 6181.8 6226.8 6230.4 6233.1 6110.2 6229.9 6268.6 6307.2 6320.6 5643.8 5723.0 5783.7 5849.8 5852.6 6132.8 6270.6 6281.9 6299.1 6308.1 5482.9 5843.1 6213.9 6217.7 6246.4 6079.2 6130.9 6205.5 6241.7 6285.2 6207.6 6244.1 6271.1 6272.9 6280.0 5689.2 5693.3 6290.2 6290.8 6317.6 4035.5 4831.1 4869.8 4936.6 5015.5 3709.9 3789.2 4424.2 4475.9 4497.3 4777.6 5664.7 5670.5 5685.5 5702.4 5134.5 5938.5 6302.6 6307.4 6344.2 5519.9 5543.5 5566.8 5605.4 5670.7 5330.9 5394.8 5406.8 5413.1 5454.4 6342.7 6349.6 6363.3 6367.2 6370.1 6093.0 6118.2 6160.5 6166.1 6177.4 6285.2 6388.7 6389.8 6408.0 6436.8 6140.3 6174.4 6175.2 6184.3 6186.2 6104.2 6150.6 6227.2 6245.8 6266.8 6132.7 6139.5 6140.0 6144.9 6146.4 5969.2 6080.1 6119.7 6123.8 6168.1 5831.1 5923.3 5948.3 6002.5 6038.9 6140.9 6159.5 6159.8 6172.0 6180.8 6030.4 6044.5 6046.3 6057.1 6082.7 20:42:20 INFO - PROCESS | 1663 | 20:42:20 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:73648.13 Median:42172.97 stddev:161844.53 (383.8%) stddev-sans-first:162205.36 20:42:20 INFO - PROCESS | 1663 | Values: 500.0 523.4 526.1 535.7 549.5 31592.5 44031.3 45088.8 45178.8 45322.3 46106.5 46657.5 46679.8 46684.8 46802.0 9703.1 9895.3 9900.4 9902.1 9918.2 9578.6 9629.0 9661.7 9744.1 9750.3 9690.1 9721.1 9774.4 9785.0 9823.3 9667.5 9686.7 9713.7 9751.6 9777.6 9682.0 9781.9 9787.5 9796.2 9800.2 34896.3 42899.6 43283.4 43292.8 43294.4 3132.2 3187.9 3218.6 3227.2 3437.2 28144.0 29946.9 30643.5 30965.4 30976.1 2615.1 2698.6 2756.8 2837.9 2847.5 483746.0 494695.0 495374.0 496984.0 498015.5 67268.3 67327.3 67763.7 67948.3 68009.7 69698.7 69791.0 69916.0 71239.2 71372.6 17215.2 17268.0 17490.7 17503.8 17509.6 12588.1 12606.1 12613.1 12630.1 12637.8 19573.8 20347.5 20464.2 20465.8 20526.0 6766.4 7380.8 7946.3 8000.2 8098.3 788.0 897.7 1032.9 1054.4 1082.7 25045.9 30036.2 31049.1 31226.2 31648.2 1010.7 1064.3 1106.5 1118.8 1141.5 11740.5 15535.8 15769.8 15879.4 15948.3 701969.0 709368.0 709462.0 717374.0 718487.0 63416.4 63762.7 63810.0 63987.4 64225.0 1293.1 1410.5 1431.9 1459.4 1462.7 30909.0 31041.4 31234.8 31753.0 32020.0 49945.3 51334.0 54910.5 54915.0 55626.9 585263.0 585554.1 591159.0 591857.0 592933.0 23724.8 26301.1 27080.5 27219.3 27425.5 41446.4 47363.8 47547.0 48565.0 48940.0 37400.6 38901.6 39065.0 39581.6 40005.8 37588.8 37640.0 38139.0 38865.6 39329.6 37879.2 38029.5 39054.6 39055.0 39139.8 33458.2 33620.8 34152.8 34248.3 34462.5 24996.5 25103.0 25205.6 25281.0 25510.6 20:42:20 INFO - PROCESS | 1663 | 20:42:20 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:33993.50 Median:11721.70 stddev:93187.57 (795.0%) stddev-sans-first:93414.82 20:42:20 INFO - PROCESS | 1663 | Values: 386.4 390.5 392.5 392.8 416.0 13786.7 20467.7 21163.3 21205.4 21282.8 16013.6 18144.5 19053.0 19635.0 20416.6 4860.9 5897.6 5904.9 5905.1 5908.6 5725.4 5877.7 5947.5 5949.5 6108.4 3872.1 4094.3 5800.2 6115.9 6140.9 6068.4 6101.0 6155.4 6155.4 6170.4 5741.6 5867.9 5913.7 5916.9 5917.1 13446.5 17364.6 25353.9 25508.7 26227.1 1903.7 2020.5 2121.8 2160.6 2473.4 9588.9 9788.3 9860.6 10019.9 10261.3 1468.5 1650.0 1661.6 1753.2 1813.5 89902.6 122978.4 126079.4 126659.4 128452.0 11128.7 11329.9 11377.0 11711.5 11716.8 14652.1 14674.6 14772.6 14799.0 14805.0 8132.0 8642.0 8730.0 8740.7 8840.5 6879.0 7736.4 8618.6 8712.9 8762.3 4566.2 4593.4 4600.0 4602.3 4660.5 1770.0 1918.8 1968.9 1969.4 1983.6 759.8 897.1 938.4 984.3 1032.2 19175.0 20232.3 20378.9 20472.5 20879.6 747.5 948.5 955.4 993.9 1001.1 9030.3 10590.7 10902.5 10907.9 10936.8 518047.0 524605.0 525619.0 565558.0 617533.9 121249.0 143633.2 147924.0 148389.0 153754.0 958.7 1025.6 1031.3 1083.2 1086.6 16449.8 18305.6 18364.6 18372.4 18427.0 54920.0 55776.0 55918.7 56001.7 56069.0 80183.6 80555.2 80999.6 82638.0 83585.0 6859.7 6976.5 7428.6 7449.8 8008.5 10222.8 11834.8 12292.8 12429.8 12753.5 10849.3 10943.0 10989.1 11040.8 11131.7 10410.8 10604.3 10637.8 10770.9 10960.4 11050.8 11138.3 11260.1 11455.9 11488.1 7231.3 7399.9 7538.6 7609.6 7669.1 10555.5 10782.9 10873.2 11027.8 11149.8 20:42:20 INFO - PROCESS | 1663 | 20:42:21 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5654.76 Median:4924.46 stddev:3188.59 (64.8%) stddev-sans-first:3181.68 20:42:21 INFO - PROCESS | 1663 | Values: 1423.0 1605.4 1635.0 1879.4 1999.5 7875.7 8660.9 8927.0 8931.1 9022.5 8602.7 8848.6 8849.3 8853.6 8877.8 2111.8 2322.9 2368.7 2403.5 2404.2 2414.6 2439.7 2440.0 2440.6 2447.3 2382.7 2406.6 2411.3 2414.5 2419.4 2085.3 2316.4 2352.4 2385.6 2387.9 2377.7 2408.3 2410.3 2411.8 2412.9 8676.8 8722.2 8729.8 8737.2 8776.5 4500.1 5152.3 5207.9 5364.7 5374.1 3352.3 3429.2 3503.6 3507.8 3511.2 978.1 989.3 1006.9 1009.3 1021.2 11430.0 11746.9 11840.6 11892.8 11924.2 4728.4 4763.2 4833.4 4839.5 4892.2 9142.2 9187.8 9257.0 9276.0 9284.5 5210.9 5226.5 5286.0 5300.9 5306.4 4446.3 4589.3 4805.7 4924.3 4924.6 3752.9 3794.6 3833.1 3881.8 3900.8 1009.8 1155.7 1156.4 1171.1 1181.0 886.3 909.5 919.4 934.7 946.4 5270.0 5907.6 5914.9 5970.2 5978.3 808.5 877.2 919.7 950.8 971.1 3373.4 3876.8 3891.3 3967.8 3994.6 10422.6 10675.8 10721.5 10806.3 10838.0 10692.1 10696.5 10731.4 10881.3 10890.3 6434.8 8243.8 8328.8 8338.0 8355.5 5736.6 5740.5 5848.7 5882.9 5897.1 9891.9 9991.1 10078.6 10080.4 10084.9 9723.7 9995.1 10288.5 10291.7 10304.2 6515.9 6697.0 6850.8 6996.5 7140.7 8232.8 8280.9 8399.5 8403.3 8416.5 7319.6 7416.3 7464.3 7550.8 7618.5 7245.6 7303.9 7323.8 7376.2 7437.6 6910.7 6978.9 6989.7 6997.4 7011.9 4653.8 4773.5 4888.0 4914.2 5088.9 4285.9 4547.4 4915.3 4955.7 4979.7 20:42:21 INFO - PROCESS | 1663 | 20:42:21 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4195.26 Median:4501.54 stddev:1261.77 (28.0%) stddev-sans-first:1230.09 20:42:21 INFO - PROCESS | 1663 | Values: 250.9 301.8 303.2 306.4 320.7 4376.4 5060.5 5146.8 5431.5 5454.1 4259.4 5131.8 5316.0 5329.8 5370.7 3844.2 4027.7 4034.2 4034.9 4049.3 3682.0 3779.3 4071.3 4075.2 4077.8 3286.4 3562.9 3859.6 3986.8 4003.4 3639.3 3730.6 3969.7 3991.0 4027.1 3091.7 3338.9 3488.0 3491.4 3509.9 4043.9 4844.7 5014.3 5019.0 5053.1 4363.6 4578.4 4601.9 4621.2 4640.3 5471.4 5521.9 5538.9 5539.9 5572.1 3289.5 3741.6 3886.4 3891.6 3902.6 5076.6 5535.5 5605.6 5798.6 5798.7 3944.3 3969.7 3984.8 3987.5 4006.0 3785.5 3989.7 4013.0 4014.8 4041.5 3730.6 4230.8 4391.6 4548.5 4620.5 4853.7 4997.0 5267.5 5483.6 5623.4 1086.5 1153.0 1215.6 1314.6 1339.9 828.0 841.8 847.0 855.5 865.0 3956.7 4327.7 4343.1 4383.8 4388.1 4092.7 4497.1 4502.5 4823.7 4836.6 3793.8 3910.6 4292.8 4315.4 4385.9 2309.4 2502.6 2555.6 2570.1 2579.8 4371.8 4457.6 4815.4 5003.8 5036.9 4459.6 4521.9 4649.0 4905.7 4972.5 4165.4 4268.9 4500.6 4697.7 4874.0 4224.5 4511.7 4528.6 4762.5 4771.0 4026.9 4129.6 4196.9 4743.7 4752.3 4331.7 4643.4 4697.9 5016.7 5051.3 3317.8 3385.6 3410.0 3421.5 3444.9 4773.5 4773.6 4790.0 4873.4 5440.7 5228.7 5381.7 5448.9 5626.3 5630.7 4230.7 4750.9 5169.4 5308.5 5602.2 4706.3 4939.7 5365.7 5448.8 5479.8 5473.2 5521.4 5530.8 5534.8 5571.7 4651.5 5251.4 5427.4 5435.9 5546.4 20:42:21 INFO - PROCESS | 1663 | 20:42:21 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18052.97 Median:568.24 stddev:25626.80 (4509.9%) stddev-sans-first:25665.37 20:42:21 INFO - PROCESS | 1663 | Values: 649.9 682.0 705.3 737.9 739.2 11312.6 14401.7 17034.9 17051.9 17066.5 15686.2 16027.4 16104.8 16201.1 16211.4 722.3 735.4 923.1 935.1 939.0 811.5 884.6 906.1 906.7 909.1 923.0 936.1 949.0 960.2 961.0 751.4 930.4 951.3 952.5 955.0 696.4 699.7 715.4 716.0 720.1 14852.8 14862.7 14918.5 14944.8 14960.3 11850.8 11948.7 11978.2 12140.8 12193.9 10827.6 10949.8 11007.7 11242.1 11474.0 3966.0 3969.0 3985.3 4112.8 4119.0 85582.0 86885.6 86946.6 87391.6 89218.6 9146.8 9192.9 9297.0 9436.2 9643.0 16700.0 20599.4 20607.9 20609.6 20630.2 6995.9 6997.1 7095.2 7133.0 7135.8 3925.1 3946.5 3966.6 3986.2 4262.5 2900.9 3361.1 3391.0 3427.6 3434.3 516.5 519.4 598.3 610.1 615.5 506.0 547.4 552.1 561.9 563.7 7464.4 7676.8 7738.8 7916.8 7983.6 544.6 555.3 557.1 566.5 570.0 3413.7 3513.9 3541.7 3714.1 3785.7 77489.2 85288.3 94417.6 94897.5 97634.0 78707.6 80236.0 81186.0 81577.6 83601.0 62210.0 62660.0 66794.7 72520.5 74620.6 8153.6 8170.5 8205.8 8255.3 8325.2 42618.5 42802.5 44042.0 44862.7 46267.5 61133.0 61783.7 61887.7 62392.7 62499.4 10978.9 11047.7 11090.8 11195.9 11199.7 11518.0 11636.3 11652.8 11926.8 12074.8 10975.6 11133.4 11170.2 11448.7 11573.7 11210.1 11624.9 11635.5 12006.9 12347.6 10667.7 10701.3 10746.6 10963.9 11057.7 8312.3 8881.6 8967.9 9115.0 9247.0 7627.3 9105.3 9211.6 9391.1 9460.5 20:42:21 INFO - PROCESS | 1663 | -------- Summary: end -------- 20:42:21 INFO - PROCESS | 1663 | 20:42:21 INFO - TEST-INFO | 1663: exit 0 20:42:21 INFO - TEST-OK | dromaeo_css | took 1115012ms 20:42:21 INFO - TEST-START | kraken 20:42:21 INFO - operating with platform_type : mac_ 20:42:21 INFO - Initialising browser for kraken test... 20:42:21 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpTPhGQ8/profile http://localhost:49278/getInfo.html) 20:42:23 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 20:42:23 INFO - PROCESS | 1670 | colorDepth:24 20:42:23 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 20:42:23 INFO - PROCESS | 1670 | __metrics 20:42:23 INFO - PROCESS | 1670 | 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" 20:42:23 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:42:24 INFO - TEST-INFO | 1670: exit 0 20:42:24 INFO - Browser initialized. 20:42:24 INFO - Running cycle 1/1 for kraken test... 20:42:24 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpTPhGQ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:42:36 INFO - PROCESS | 1672 | RSS: Main: 239116288 20:42:36 INFO - PROCESS | 1672 | 20:42:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 20:42:39 INFO - PROCESS | 1672 | RSS: Main: 287506432 20:42:39 INFO - PROCESS | 1672 | 20:42:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 20:42:43 INFO - PROCESS | 1672 | RSS: Main: 295874560 20:42:43 INFO - PROCESS | 1672 | 20:42:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 20:42:47 INFO - PROCESS | 1672 | RSS: Main: 383967232 20:42:47 INFO - PROCESS | 1672 | 20:42:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 20:42:50 INFO - PROCESS | 1672 | RSS: Main: 283824128 20:42:50 INFO - PROCESS | 1672 | 20:42:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:42:53 INFO - PROCESS | 1672 | RSS: Main: 288337920 20:42:53 INFO - PROCESS | 1672 | 20:42:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 20:42:56 INFO - PROCESS | 1672 | RSS: Main: 325091328 20:42:56 INFO - PROCESS | 1672 | 20:42:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 20:43:00 INFO - PROCESS | 1672 | RSS: Main: 329080832 20:43:00 INFO - PROCESS | 1672 | 20:43:03 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 20:43:03 INFO - PROCESS | 1672 | RSS: Main: 326287360 20:43:03 INFO - PROCESS | 1672 | 20:43:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:43:06 INFO - PROCESS | 1672 | RSS: Main: 315994112 20:43:06 INFO - PROCESS | 1672 | 20:43:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:43:08 INFO - PROCESS | 1672 | RSS: Main: 322875392 20:43:08 INFO - PROCESS | 1672 | 20:43:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:43:12 INFO - PROCESS | 1672 | RSS: Main: 326066176 20:43:12 INFO - PROCESS | 1672 | 20:43:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:43:15 INFO - PROCESS | 1672 | RSS: Main: 327417856 20:43:15 INFO - PROCESS | 1672 | 20:43:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:43:19 INFO - PROCESS | 1672 | RSS: Main: 323866624 20:43:19 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 20:43:21 INFO - PROCESS | 1672 | RSS: Main: 370868224 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | __start_tp_report 20:43:21 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 20:43:21 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 20:43:21 INFO - PROCESS | 1672 | |i|pagename|runs| 20:43:21 INFO - PROCESS | 1672 | |0;ai-astar;112;106;112;112;113;111;112;113;111;111 20:43:21 INFO - PROCESS | 1672 | |1;audio-beat-detection;156;123;142;200;120;141;139;116;173;135 20:43:21 INFO - PROCESS | 1672 | |2;audio-dft;185;185;185;181;185;184;183;181;183;181 20:43:21 INFO - PROCESS | 1672 | |3;audio-fft;77;73;67;124;57;61;57;58;111;56 20:43:21 INFO - PROCESS | 1672 | |4;audio-oscillator;96;104;171;81;82;76;101;79;83;97 20:43:21 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;89;84;85;82;85;87;87;82;86;82 20:43:21 INFO - PROCESS | 1672 | |6;imaging-darkroom;92;92;93;93;92;93;93;94;96;93 20:43:21 INFO - PROCESS | 1672 | |7;imaging-desaturate;83;86;86;87;87;86;87;83;85;86 20:43:21 INFO - PROCESS | 1672 | |8;json-parse-financial;58;57;60;58;57;71;52;120;55;56 20:43:21 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;66;76;73;76;73;68;73;102;110;81 20:43:21 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;97;106;107;110;85;84;85;86;86;87 20:43:21 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;142;149;154;149;152;150;138;141;257;149 20:43:21 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;173;166;168;172;167;187;194;198;233;179 20:43:21 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;66;70;77;82;66;66;68;66;67;65 20:43:21 INFO - PROCESS | 1672 | __end_tp_report 20:43:21 INFO - PROCESS | 1672 | __start_cc_report 20:43:21 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2240 20:43:21 INFO - PROCESS | 1672 | __end_cc_report 20:43:21 INFO - PROCESS | 1672 | __startTimestamp1463715801570__endTimestamp 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | ------- Summary: start ------- 20:43:21 INFO - PROCESS | 1672 | Number of tests: 14 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.00 (1.8%) stddev-sans-first:2.11 20:43:21 INFO - PROCESS | 1672 | Values: 112.0 106.0 112.0 112.0 113.0 111.0 112.0 113.0 111.0 111.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:141.50 stddev:25.90 (18.3%) stddev-sans-first:27.14 20:43:21 INFO - PROCESS | 1672 | Values: 156.0 123.0 142.0 200.0 120.0 141.0 139.0 116.0 173.0 135.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.50 stddev:1.77 (1.0%) stddev-sans-first:1.76 20:43:21 INFO - PROCESS | 1672 | Values: 185.0 185.0 185.0 181.0 185.0 184.0 183.0 181.0 183.0 181.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:74.10 Median:59.50 stddev:24.16 (40.6%) stddev-sans-first:25.61 20:43:21 INFO - PROCESS | 1672 | Values: 77.0 73.0 67.0 124.0 57.0 61.0 57.0 58.0 111.0 56.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:81.50 stddev:27.86 (34.2%) stddev-sans-first:29.54 20:43:21 INFO - PROCESS | 1672 | Values: 96.0 104.0 171.0 81.0 82.0 76.0 101.0 79.0 83.0 97.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:85.50 stddev:2.42 (2.8%) stddev-sans-first:2.07 20:43:21 INFO - PROCESS | 1672 | Values: 89.0 84.0 85.0 82.0 85.0 87.0 87.0 82.0 86.0 82.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.20 (1.3%) stddev-sans-first:1.20 20:43:21 INFO - PROCESS | 1672 | Values: 92.0 92.0 93.0 93.0 92.0 93.0 93.0 94.0 96.0 93.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.51 (1.8%) stddev-sans-first:1.27 20:43:21 INFO - PROCESS | 1672 | Values: 83.0 86.0 86.0 87.0 87.0 86.0 87.0 83.0 85.0 86.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:57.50 stddev:20.16 (35.1%) stddev-sans-first:21.25 20:43:21 INFO - PROCESS | 1672 | Values: 58.0 57.0 60.0 58.0 57.0 71.0 52.0 120.0 55.0 56.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:73.00 stddev:14.54 (19.9%) stddev-sans-first:14.54 20:43:21 INFO - PROCESS | 1672 | Values: 66.0 76.0 73.0 76.0 73.0 68.0 73.0 102.0 110.0 81.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:85.50 stddev:10.60 (12.4%) stddev-sans-first:11.16 20:43:21 INFO - PROCESS | 1672 | Values: 97.0 106.0 107.0 110.0 85.0 84.0 85.0 86.0 86.0 87.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:149.50 stddev:35.13 (23.5%) stddev-sans-first:36.77 20:43:21 INFO - PROCESS | 1672 | Values: 142.0 149.0 154.0 149.0 152.0 150.0 138.0 141.0 257.0 149.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.70 Median:183.00 stddev:20.72 (11.3%) stddev-sans-first:21.61 20:43:21 INFO - PROCESS | 1672 | Values: 173.0 166.0 168.0 172.0 167.0 187.0 194.0 198.0 233.0 179.0 20:43:21 INFO - PROCESS | 1672 | 20:43:21 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:67.50 stddev:5.68 (8.4%) stddev-sans-first:5.89 20:43:21 INFO - PROCESS | 1672 | Values: 66.0 70.0 77.0 82.0 66.0 66.0 68.0 66.0 67.0 65.0 20:43:21 INFO - PROCESS | 1672 | -------- Summary: end -------- 20:43:21 INFO - PROCESS | 1672 | 20:43:22 INFO - TEST-INFO | 1672: exit 0 20:43:22 INFO - TEST-OK | kraken | took 61014ms 20:43:22 INFO - SUITE-END | took 1176s 20:43:22 INFO - Completed test suite (00:19:36) 20:43:22 INFO - javascript benchmark 20:43:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3231.2245061185317, 3432.0733401981465, 3458.8097654628987, 3461.2558299965503, 3504.596971348296, 5583.553315734742, 5606.775728970846, 5643.746031428585, 5645.802396916109, 5678.687672178028, 5546.306711661053, 5592.972035139829, 5677.006523858318, 5734.971325143379, 5762.855928601787, 4984.700917944917, 5052.242163675442, 5083.949160508385, 5091.974540127294, 5159.251908473269, 5160.303359046518, 5161.406438259611, 5184.300119483878, 5194.818181363653, 5217.191335343009, 5064.189729643258, 5088.6437949343745, 5131.923021154686, 5139.614528910347, 5183.429822719498, 5090.872728181788, 5103.464136265713, 5136.948630513684, 5139.640225184201, 5155.71643559604, 4517.59341659249, 4921.8523444296725, 5060.822871199527, 5175.223716442526, 5191.922121168185, 5500.147477141009, 5507.807226747084, 5633.380328163898, 5636.887262254773, 5708.286464191976, 6175.938240617581, 6181.814545563595, 6226.844328891769, 6230.408111229426, 6233.1273621693, 6110.172019488931, 6229.906551401733, 6268.623882567061, 6307.211598550181, 6320.6207627542035, 5643.802466913638, 5722.97138514309, 5783.71081445926, 5849.795257165979, 5852.619579727304, 6132.816015519542, 6270.561060725706, 6281.937180628227, 6299.149614801943, 6308.148399965946, 5482.890342193134, 5843.094320380348, 6213.875722485525, 6217.658028808369, 6246.437820596168, 6079.240094988127, 6130.938690613037, 6205.472534834499, 6241.687915604292, 6285.182926219562, 6207.565470417027, 6244.094606282064, 6271.122042913996, 6272.905906411407, 6279.968600157062, 5689.175069614883, 5693.349484679393, 6290.21372328459, 6290.842728931813, 6317.6209427434505, 4035.4753881995152, 4831.0820944021225, 4869.780859861315, 4936.605071594264, 5015.523525265094, 3709.851605935786, 3789.2232092420986, 4424.203643344164, 4475.865724028285, 4497.3254011897825, 4777.593904518086, 5664.688442135722, 5670.5463562914865, 5685.488306052475, 5702.429757024264, 5134.460881607452, 5938.485686150004, 6302.621842689453, 6307.369263073656, 6344.206974128234, 5519.917201241985, 5543.473370029841, 5566.832995010156, 5605.439456054362, 5670.659760414388, 5330.946690533123, 5394.838154855361, 5406.83779486616, 5413.079776438015, 5454.400015998237, 6342.746290148435, 6349.619022858643, 6363.331850155667, 6367.235931688135, 6370.140031095742, 6092.969535152296, 6118.162361256354, 6160.537959653134, 6166.075088736726, 6177.413145751147, 6285.151504546828, 6388.744450221939, 6389.808305750649, 6408.0, 6436.774712885027, 6140.263168419819, 6174.413430724074, 6175.166352542349, 6184.257889053342, 6186.226721659793, 6104.186323682738, 6150.630962142286, 6227.159333489921, 6245.812625621239, 6266.811995640138, 6132.692058233554, 6139.5228034422325, 6140.04415716754, 6144.907826382519, 6146.385361463817, 5969.164316995537, 6080.118382834553, 6119.654885716854, 6123.801220575414, 6168.074788781719, 5831.125331200354, 5923.259592550931, 5948.256467941507, 6002.459778619946, 6038.9396106038375, 6140.907886381798, 6159.5072394209255, 6159.81520554384, 6172.043333283348, 6180.783672571439, 6030.44002560641, 6044.486218671462, 6046.334903160561, 6057.061155520902, 6082.72627731762], "value": 5646.660876870565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.9725015124168, 523.4373048972353, 526.0872386409582, 535.6812696445613, 549.5164255455198, 31592.52611210834, 44031.339529907076, 45088.77455612725, 45178.7741061295, 45322.280663160236, 46106.538934610646, 46657.533424665744, 46679.76660116695, 46684.83287917795, 46802.0, 9703.07820757027, 9895.257855660819, 9900.35647682902, 9902.059304366074, 9918.206543476505, 9578.56896439662, 9629.037096290385, 9661.71014869555, 9744.123028927395, 9750.268729845255, 9690.127888490035, 9721.125098741111, 9774.364666296704, 9784.951075244613, 9823.263255255848, 9667.516624168764, 9686.70939871805, 9713.70858874235, 9751.609935602568, 9777.608895644202, 9681.951590242004, 9781.90218097817, 9787.510624468749, 9796.216302695768, 9800.215982721365, 34896.302073958635, 42899.57100428987, 43283.350749738776, 43292.78353608243, 43294.35058474125, 3132.2210521587635, 3187.920301992469, 3218.6298575663864, 3227.241598224439, 3437.1750779812915, 28144.0, 29946.850265748748, 30643.540346894813, 30965.38069238625, 30976.0707178785, 2615.0977912620115, 2698.649175607178, 2756.834589924611, 2837.886484540616, 2847.4589827932627, 483746.0, 494695.0, 495374.0, 496984.0, 498015.5099224517, 67268.32731672718, 67327.3267267321, 67763.66118169378, 67948.32051679518, 68009.65995170092, 69698.65150674214, 69790.95313570199, 69916.0, 71239.1504339831, 71372.64313678502, 17215.22531486086, 17268.0, 17490.737638935425, 17503.82496175022, 17509.64980700379, 12588.118831681877, 12606.117571770072, 12613.117081804186, 12630.052746044083, 12637.810432843331, 19573.804261957197, 20347.491312717004, 20464.181432742524, 20465.795342046687, 20525.97370131517, 6766.357196066366, 7380.77857664271, 7946.284834364935, 8000.239977202156, 8098.271155596025, 787.9803004924922, 897.7486303834937, 1032.9276950613385, 1054.393723608928, 1082.6968448834339, 25045.872935717936, 30036.24909377283, 31049.068527944197, 31226.21934451657, 31648.208794779854, 1010.6917390195914, 1064.3347907557777, 1106.5407855739938, 1118.7594667146602, 1141.4578075414279, 11740.530378784924, 15535.844641553665, 15769.817263705268, 15879.364825406854, 15948.2823272953, 701969.0, 709368.0, 709462.0, 717373.9575625479, 718487.0, 63416.36583634197, 63762.68118659377, 63810.0, 63987.36012639907, 64225.0, 1293.0560690695656, 1410.4781230944616, 1431.8711315981611, 1459.4381163252012, 1462.6782107936237, 30909.0, 31041.447927603076, 31234.843825780725, 31753.0, 32020.0, 49945.25082123699, 51334.0, 54910.45089549053, 54915.0, 55626.88746224972, 585263.0, 585554.0722296531, 591159.0, 591857.0407147937, 592933.0, 23724.762752372946, 26301.07946221873, 27080.458390832468, 27219.319517012234, 27425.451490970467, 41446.378304324855, 47363.76318118387, 47547.0, 48565.0, 48940.02119957652, 37400.62599373971, 38901.638442654374, 39065.0, 39581.604183958945, 40005.79982600527, 37588.81205593955, 37640.0, 38139.0, 38865.63970261027, 39329.60670393259, 37879.2424151521, 38029.47882084685, 39054.6094539051, 39055.0, 39139.804300978314, 33458.16354591155, 33620.83189584036, 34152.82923585465, 34248.315033699684, 34462.48306275358, 24996.500069998136, 25103.0, 25205.621915671647, 25280.977521797846, 25510.617340740275], "value": 20436.55252621774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.3528589612399, 390.50795997043724, 392.5152436740627, 392.82322954670406, 415.95008598968127, 13786.724265514684, 20467.692984605223, 21163.259285924996, 21205.363839084832, 21282.787172128275, 16013.599660008476, 18144.45566633, 19053.0, 19635.0, 20416.591668166664, 4860.927086093702, 5897.587168898179, 5904.88190236195, 5905.055191169414, 5908.61593996391, 5725.427457254283, 5877.67672777997, 5947.464728174464, 5949.464548190662, 6108.358622344656, 3872.0900588361715, 4094.324436467994, 5800.187973683687, 6115.908261376083, 6140.938590614103, 6068.362821903681, 6101.0, 6155.353687862755, 6155.415235552616, 6170.382961703838, 5741.62679425836, 5867.911981320283, 5913.704314784243, 5916.881662366729, 5917.14201440793, 13446.462141514328, 17364.56588585283, 25353.87323063391, 25508.74491255082, 26227.082052128273, 1903.7334773131772, 2020.5150763816725, 2121.7772133926023, 2160.621891169055, 2473.4434752180796, 9588.856167157428, 9788.314817962746, 9860.556274967643, 10019.949900250525, 10261.333013354108, 1468.537410715632, 1649.9605248693367, 1661.59290973711, 1753.2198171813548, 1813.5103522049105, 89902.5504872478, 122978.38510807478, 126079.3696031514, 126659.36670316682, 128452.0, 11128.721781955353, 11329.943350283196, 11377.0, 11711.531538738525, 11716.824247636292, 14652.12087274767, 14674.55976320712, 14772.630684232765, 14799.0, 14805.0, 8132.0, 8641.956790216134, 8730.0, 8740.694075707319, 8840.469571825712, 6879.042011934816, 7736.419768517271, 8618.569071546397, 8712.91287087121, 8762.342824288204, 4566.1552612766745, 4593.402857628554, 4600.033992861469, 4602.286645569942, 4660.53394660538, 1769.973450398245, 1918.7697476302653, 1968.9015549222481, 1969.4091772468203, 1983.5537004173946, 759.7530802489213, 897.1432282170512, 938.3666025432806, 984.3060642247199, 1032.2206733915846, 19175.0, 20232.291869784487, 20378.89810550938, 20472.488187795425, 20879.582408351747, 747.5327920049968, 948.4641177734696, 955.3742298794301, 993.9055789700111, 1001.0890090018048, 9030.32272579546, 10590.682279531626, 10902.5093870776, 10907.890921090846, 10936.835947460795, 518047.0, 524605.0, 525619.0, 565558.0, 617533.9123304355, 121249.0, 143633.15661498046, 147924.0, 148389.0, 153754.0, 958.7296831697888, 1025.5795123999274, 1031.2832581356035, 1083.1984331594563, 1086.576235268246, 16449.753253700965, 18305.633887321914, 18364.62265330132, 18372.35696750607, 18427.0, 54920.0, 55776.0, 55918.720406397704, 56001.71999139978, 56069.0, 80183.59908200422, 80555.19444805477, 80999.59500202461, 82638.0, 83585.0, 6859.691313890787, 6976.511644185061, 7428.6285685716575, 7449.813754656177, 8008.4783891060515, 10222.846657300295, 11834.76330473403, 12292.754144916873, 12429.813552796897, 12753.482039483995, 10849.294795838245, 10943.0, 10989.120870330195, 11040.834387484034, 11131.721706957067, 10410.843837342294, 10604.310719803188, 10637.787244255227, 10770.946145269538, 10960.397178155277, 11050.834237486604, 11138.33170009802, 11260.09919206478, 11455.885441145481, 11488.080953523531, 7231.2768723127265, 7399.852002960019, 7538.623068846646, 7609.619519023916, 7669.118051424158, 10555.525001374803, 10782.946085269523, 10873.238873279111, 11027.779444410906, 11149.83275250888], "value": 9379.458036178685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.957311280662, 1605.4140238812836, 1635.0271588404898, 1879.436169149255, 1999.4903847595062, 7875.684972601096, 8660.870086948693, 8927.0, 8931.106889311082, 9022.503762293087, 8602.655893764257, 8848.601812918418, 8849.336299777526, 8853.5573221339, 8877.778055548599, 2111.788821117891, 2322.907083716649, 2368.6802281691926, 2403.519296140779, 2404.218628945591, 2414.625733011377, 2439.695038120235, 2440.023990403835, 2440.5973014452593, 2447.339218411028, 2382.65451509531, 2406.639004149383, 2411.3248290478696, 2414.468816860288, 2419.4314336130997, 2085.2910010596393, 2316.3745788636975, 2352.3883790214495, 2385.5705972924866, 2387.916422925189, 2377.6909001829827, 2408.277516744987, 2410.3251089694913, 2411.8070554355786, 2412.8793560322088, 8676.783080422976, 8722.215000649972, 8729.825403491957, 8737.213650771397, 8776.517291548962, 4500.144972455223, 5152.252923326134, 5207.947920520822, 5364.6512976656395, 5374.113271310229, 3352.2625022495013, 3429.159855835311, 3503.5970863350785, 3507.7719948203285, 3511.157322242669, 978.1441238915949, 989.3470309595705, 1006.8942761010135, 1009.3237530854345, 1021.1524434719165, 11430.0, 11746.941265293619, 11840.585579504675, 11892.821607675893, 11924.22492537991, 4728.361671174416, 4763.21379482694, 4833.371661684029, 4839.491853355347, 4892.168331383673, 9142.222911052502, 9187.770305742277, 9257.0, 9276.0, 9284.48935308551, 5210.921836172461, 5226.451222621646, 5286.022085914118, 5300.867478312995, 5306.389765176977, 4446.3108218226225, 4589.2886602576655, 4805.735684537314, 4924.285978533093, 4924.630652701062, 3752.9249415011545, 3794.6395092466173, 3833.0609000794984, 3881.80590970456, 3900.7659540427667, 1009.8081364540714, 1155.7226265696172, 1156.392893730798, 1171.0690001448868, 1181.0, 886.2732559301311, 909.4770506958394, 919.4207649181106, 934.7427709730401, 946.3848498476045, 5270.0, 5907.6406884258295, 5914.852128696817, 5970.164177015221, 5978.28260608721, 808.5108509351875, 877.1710733356916, 919.7056941778567, 950.7718147644515, 971.062924278075, 3373.4433818420175, 3876.8061596920043, 3891.2606604745006, 3967.761934283952, 3994.6205110514456, 10422.5830966762, 10675.83986240207, 10721.517531711092, 10806.297590656583, 10838.0, 10692.144628429707, 10696.51865666047, 10731.40977246259, 10881.347119172875, 10890.346579205114, 6434.774782882577, 8243.793905152299, 8328.8334233315, 8338.0, 8355.456895301786, 5736.598438109292, 5740.483356497935, 5848.678322692293, 5882.911756323573, 5897.144913987534, 9891.851622225528, 9991.100800927952, 10078.647247346307, 10080.395176289447, 10084.949575252076, 9723.65967191145, 9995.100440960057, 10288.485575721334, 10291.691249262534, 10304.227182961158, 6515.869682606416, 6697.028930804909, 6850.828729281808, 6996.510244282851, 7140.7143714252925, 8232.794180145305, 8280.917190828255, 8399.496030238206, 8403.327733781161, 8416.4950102994, 7319.560826350436, 7416.295451932057, 7464.290892365217, 7550.848983020199, 7618.504797188165, 7245.601491917784, 7303.853922921406, 7323.780286591411, 7376.151742549461, 7437.553746775211, 6910.654467276717, 6978.860422791618, 6989.685464154024, 6997.405220556309, 7011.894821577578, 4653.790579423866, 4773.511884034879, 4887.975560122177, 4914.164592019437, 5088.898222035613, 4285.892852678583, 4547.431571053618, 4915.26271059344, 4955.72743499111, 4979.676321039121], "value": 4535.859410133895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.92346834215562, 301.7962875059333, 303.2479450961615, 306.43615747025467, 320.67771889251327, 4376.409184760057, 5060.468650791665, 5146.819861304855, 5431.511163995239, 5454.118329176437, 4259.382389553512, 5131.769070391832, 5315.973420132904, 5329.786808527654, 5370.737876598996, 3844.1927195288954, 4027.7180597358197, 4034.152827906143, 4034.899127521806, 4049.3318602430563, 3681.9631803681887, 3779.3197224499577, 4071.30787766079, 4075.205334959684, 4077.755334679914, 3286.3568215892055, 3562.895502394253, 3859.614038596146, 3986.7607943523335, 4003.4195041718935, 3639.344917914774, 3730.6196707218232, 3969.6824254059607, 3990.980045099756, 4027.1140349123148, 3091.690830916913, 3338.929863484655, 3488.0, 3491.4413693809124, 3509.94735078974, 4043.898902527431, 4844.733539655317, 5014.297998280207, 5018.971110922253, 5053.065182941167, 4363.6072753452345, 4578.381918441001, 4601.884952876171, 4621.237495813187, 4640.327152562893, 5471.425500322449, 5521.861953451155, 5538.944610553924, 5539.889202215933, 5572.136318870582, 3289.490129030004, 3741.5697194822674, 3886.378179491268, 3891.649751522348, 3902.5902280260448, 5076.593872490229, 5535.501804837584, 5605.607607467479, 5798.623089499211, 5798.710064496759, 3944.2505923874373, 3969.7419667721506, 3984.8406063757125, 3987.501562304712, 4005.959940400617, 3785.5268091488565, 3989.740666856645, 4013.0, 4014.7791871447353, 4041.4948131483566, 3730.5896351401325, 4230.809613567397, 4391.5827996340295, 4548.499665036843, 4620.514845941195, 4853.6602437829715, 4996.975619997892, 5267.499587539178, 5483.616146869761, 5623.437656234344, 1086.4947799273375, 1153.031453578981, 1215.6474622359417, 1314.5859054397833, 1339.9129056611484, 827.9917200828035, 841.7516832534417, 846.9667006252362, 855.4653341661461, 864.9740507784777, 3956.723029387973, 4327.653787696977, 4343.066240758252, 4383.824647014148, 4388.125777615667, 4092.6521245693853, 4497.145542346943, 4502.482214545304, 4823.662343635982, 4836.5888899443235, 3793.7534060286544, 3910.6089391061237, 4292.849750258789, 4315.438992930899, 4385.868423947287, 2309.411100169446, 2502.5745623244093, 2555.6327364859712, 2570.1494923654936, 2579.7678208960906, 4371.759553224603, 4457.5988161065015, 4815.446223684251, 5003.847576027152, 5036.924446133311, 4459.620932220798, 4521.8973177472635, 4648.976755116203, 4905.7301848397965, 4972.502749725071, 4165.417441186484, 4268.8719338420515, 4500.604812508133, 4697.718136911796, 4873.952100298382, 4224.4930608327195, 4511.729296242104, 4528.592426681615, 4762.547557981986, 4770.994273607605, 4026.879193624196, 4129.6254653574915, 4196.874093777192, 4743.667943244078, 4752.310914917387, 4331.653467722512, 4643.442786865598, 4697.906041879075, 5016.673916195436, 5051.318072060225, 3317.822173128505, 3385.559877215946, 3410.0110967819055, 3421.469672200813, 3444.8622055117517, 4773.522647735199, 4773.56792962103, 4789.95210047895, 4873.415190177201, 5440.700761458, 5228.686278823283, 5381.704006279692, 5448.945510544844, 5626.2685850839125, 5630.662160270397, 4230.661547076288, 4750.904981900412, 5169.431362550192, 5308.522232999048, 5602.215689803349, 4706.306411623909, 4939.678920870132, 5365.731713414236, 5448.839672989736, 5479.835604931858, 5473.17902314656, 5521.420250873761, 5530.834074977757, 5534.806281780279, 5571.66570005801, 4651.534846515457, 5251.36983561975, 5427.375851777097, 5435.945640543544, 5546.362168350692], "value": 3798.023134514326, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.8732747114313, 681.9897701534478, 705.2700455029046, 737.9336858239842, 739.2090463204373, 11312.60405885793, 14401.71196576069, 17034.914825425854, 17051.914740426313, 17066.488005359828, 15686.21568921555, 16027.358905643789, 16104.83895161048, 16201.10893900835, 16211.432599858977, 722.3426681719636, 735.4043224987751, 923.1138107416871, 935.050923312838, 938.9905851209943, 811.4968719393954, 884.6151923913086, 906.1074841281365, 906.6871929184421, 909.0954500272205, 922.9815403691922, 936.0826390137669, 948.9620415183384, 960.1982344742148, 961.0341606685272, 751.3876190904396, 930.4138392812553, 951.3150531617224, 952.5284983932928, 954.992482930508, 696.4254490045682, 699.6501749125438, 715.3633266392915, 716.0, 720.0818955831305, 14852.777208341884, 14862.70274594513, 14918.477853275082, 14944.775828362475, 14960.251987400587, 11850.822237666442, 11948.70128246792, 11978.221415607957, 12140.817887731691, 12193.93903030488, 10827.566897324097, 10949.83575246372, 11007.669769906915, 11242.100631949424, 11473.967342939175, 3966.0481484443526, 3969.0, 3985.2826491231567, 4112.814923328458, 4119.0, 85582.0, 86885.56557217173, 86946.56526717325, 87391.56304218438, 89218.55390723005, 9146.817063658822, 9192.862107068398, 9297.0, 9436.150746432853, 9642.987486313976, 16700.0, 20599.38201853947, 20607.896960515103, 20609.587808243752, 20630.17479300811, 6995.8950615740805, 6997.145547203036, 7095.184053833876, 7133.0, 7135.750248741269, 3925.0776067624083, 3946.5461471931094, 3966.6033396660105, 3986.22268657609, 4262.531121576624, 2900.941981160365, 3361.058903507022, 3391.016605184518, 3427.622961474236, 3434.261633748756, 516.4938360406823, 519.4026869100564, 598.297001023804, 610.1001023490317, 615.5291202230297, 505.9696418214919, 547.3951283831309, 552.1193696054786, 561.9466150715675, 563.7294098832583, 7464.385189030521, 7676.808079797938, 7738.845223095619, 7916.841663166704, 7983.640736166886, 544.5589072851003, 555.3363730342202, 557.1225320120826, 566.5043087298614, 569.9658020518699, 3413.709834664032, 3513.9297214055578, 3541.698955588804, 3714.1271801126713, 3785.73499855013, 77489.2251077482, 85288.29423411498, 94417.6395590102, 94897.525512372, 97634.0, 78707.60646196733, 80236.0, 81186.0, 81577.59211203906, 83601.0, 62209.9999999991, 62660.0, 66794.66602666955, 72520.54958900792, 74620.62689686625, 8153.586665343832, 8170.468919520212, 8205.835883282181, 8255.298299644597, 8325.209105135002, 42618.45960025177, 42802.50191243291, 44042.0, 44862.65412037644, 46267.537324626326, 61133.0, 61783.691081544304, 61887.69056154691, 62392.68803655953, 62499.43751406251, 10978.917153320923, 11047.72380690457, 11090.778184436427, 11195.944020280172, 11199.72000699989, 11518.0, 11636.301821890713, 11652.766944661229, 11926.761464770829, 12074.818877716665, 10975.560977560808, 11133.387663678575, 11170.162237832032, 11448.713782155513, 11573.652790416301, 11210.103191744478, 11624.94187529057, 11635.534578617098, 12006.879931200576, 12347.567835125728, 10667.679969600924, 10701.250912436055, 10746.62386816489, 10963.945180274048, 11057.668269951915, 8312.25189732927, 8881.600327985385, 8967.879660427992, 9115.0, 9247.0, 7627.313541781266, 9105.317101217302, 9211.585478653342, 9391.060893910555, 9460.526973651153], "value": 6767.841810183816, "unit": "score"}], "name": "dromaeo_css", "value": 7082.434152276357, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 112.0, 112.0, 113.0, 111.0, 112.0, 113.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 142.0, 200.0, 120.0, 141.0, 139.0, 116.0, 173.0, 135.0], "value": 144.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 181.0, 185.0, 184.0, 183.0, 181.0, 183.0, 181.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 67.0, 124.0, 57.0, 61.0, 57.0, 58.0, 111.0, 56.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 104.0, 171.0, 81.0, 82.0, 76.0, 101.0, 79.0, 83.0, 97.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 85.0, 82.0, 85.0, 87.0, 87.0, 82.0, 86.0, 82.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 94.0, 96.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 87.0, 87.0, 86.0, 87.0, 83.0, 85.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 60.0, 58.0, 57.0, 71.0, 52.0, 120.0, 55.0, 56.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 73.0, 76.0, 73.0, 68.0, 73.0, 102.0, 110.0, 81.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 107.0, 110.0, 85.0, 84.0, 85.0, 86.0, 86.0, 87.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 154.0, 149.0, 152.0, 150.0, 138.0, 141.0, 257.0, 149.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 168.0, 172.0, 167.0, 187.0, 194.0, 198.0, 233.0, 179.0], "value": 183.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 77.0, 82.0, 66.0, 66.0, 68.0, 66.0, 67.0, 65.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1522.3999999999999, "alertThreshold": 2.0}]} 20:43:22 INFO - Return code: 0 20:43:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:43:22 ERROR - # TBPL SUCCESS # 20:43:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:43:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:43:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:43:22 INFO - 2016-05-19 20:43:22.908 screenresolution[1676:10501] starting screenresolution argv=screenresolution get 20:43:22 INFO - 2016-05-19 20:43:22.911 screenresolution[1676:10501] Display 0: 1600x1200x32@60 20:43:22 INFO - 2016-05-19 20:43:22.918 screenresolution[1677:10505] starting screenresolution argv=screenresolution list 20:43:22 INFO - Available Modes on Display 0 20:43:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:43:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:43:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:43:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:43:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:43:23 INFO - Intel Iris: 20:43:23 INFO - Chipset Model: Intel Iris 20:43:23 INFO - Type: GPU 20:43:23 INFO - Bus: Built-In 20:43:23 INFO - VRAM (Dynamic, Max): 1536 MB 20:43:23 INFO - Vendor: Intel (0x8086) 20:43:23 INFO - Device ID: 0x0a2e 20:43:23 INFO - Revision ID: 0x0009 20:43:23 INFO - Displays: 20:43:23 INFO - Display: 20:43:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:43:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:43:23 INFO - Display Serial Number: 1600x1200 60 20:43:23 INFO - Main Display: Yes 20:43:23 INFO - Mirror: Off 20:43:23 INFO - Online: Yes 20:43:23 INFO - Rotation: Supported 20:43:23 INFO - Return code: 0 20:43:23 INFO - Running post-action listener: _resource_record_post_action 20:43:23 INFO - ##### 20:43:23 INFO - ##### Finished run-tests step (success) 20:43:23 INFO - ##### 20:43:23 INFO - Running post-run listener: _resource_record_post_run 20:43:23 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 42364416; Write bytes: 706735616; Read time: 482; Write time: 2266 20:43:23 INFO - TinderboxPrint: CPU usage
53.8% 20:43:23 INFO - TinderboxPrint: I/O read bytes / time
42,364,416 / 482 20:43:23 INFO - TinderboxPrint: I/O write bytes / time
706,735,616 / 2,266 20:43:23 INFO - TinderboxPrint: CPU idle
2,237.0 (46.2%) 20:43:23 INFO - TinderboxPrint: CPU system
967.6 (20.0%) 20:43:23 INFO - TinderboxPrint: CPU user
1,641.1 (33.9%) 20:43:23 INFO - TinderboxPrint: Swap in / out
429,150,208 / 0 20:43:23 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 373430784; Write bytes: 368474112; Read time: 27876; Write time: 712 20:43:23 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 41442816; Write bytes: 308049408; Read time: 468; Write time: 1480 20:43:23 INFO - Running post-run listener: _upload_blobber_files 20:43:23 INFO - Blob upload gear active. 20:43:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:43:23 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 20:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:43:24 INFO - (blobuploader) - INFO - Open directory for files ... 20:43:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:43:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:43:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:25 INFO - (blobuploader) - INFO - Done attempting. 20:43:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:43:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:43:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:26 INFO - (blobuploader) - INFO - Done attempting. 20:43:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:43:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:28 INFO - (blobuploader) - INFO - Done attempting. 20:43:28 INFO - (blobuploader) - INFO - Iteration through files over. 20:43:28 INFO - Return code: 0 20:43:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:43:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:43:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a5acbc272557b0f136838ad892b57e509ae7984647f33aa5760af0e845cf8ae09329240738c7971ef3db394f2ea45b3d3e2d20706559f972b49ea8021243fdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/299dbd9b60ff40c0413ae8b66fed60aedaf17c55774cf8cc497a2f4b5662ce0e4e072e9d83ddf8d86ffb8dc7a11e07d088693db94d62092ecb5ab2f0b10ec068", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5602971298fd7ff49e17d5d5c3977955e2630e1ecd954230b6a4627dbb4f12592306233d5c1556237f75d0b888ff70d33681846a436bc940e67f0a7a35cd20a9"} 20:43:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:43:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:43:28 INFO - Contents: 20:43:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a5acbc272557b0f136838ad892b57e509ae7984647f33aa5760af0e845cf8ae09329240738c7971ef3db394f2ea45b3d3e2d20706559f972b49ea8021243fdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/299dbd9b60ff40c0413ae8b66fed60aedaf17c55774cf8cc497a2f4b5662ce0e4e072e9d83ddf8d86ffb8dc7a11e07d088693db94d62092ecb5ab2f0b10ec068", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5602971298fd7ff49e17d5d5c3977955e2630e1ecd954230b6a4627dbb4f12592306233d5c1556237f75d0b888ff70d33681846a436bc940e67f0a7a35cd20a9"} 20:43:28 INFO - Running post-run listener: copy_logs_to_upload_dir 20:43:28 INFO - Copying logs to upload dir... 20:43:28 INFO - mkdir: /builds/slave/test/build/upload/logs 20:43:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.352689 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-19 20:43:28.947715) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 20:43:28.951002) ========= 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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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/Fx-Team/sha512/7a5acbc272557b0f136838ad892b57e509ae7984647f33aa5760af0e845cf8ae09329240738c7971ef3db394f2ea45b3d3e2d20706559f972b49ea8021243fdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/299dbd9b60ff40c0413ae8b66fed60aedaf17c55774cf8cc497a2f4b5662ce0e4e072e9d83ddf8d86ffb8dc7a11e07d088693db94d62092ecb5ab2f0b10ec068", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5602971298fd7ff49e17d5d5c3977955e2630e1ecd954230b6a4627dbb4f12592306233d5c1556237f75d0b888ff70d33681846a436bc940e67f0a7a35cd20a9"} build_url:https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/GtSwxerDQ_6cM7uqt4cpoQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a5acbc272557b0f136838ad892b57e509ae7984647f33aa5760af0e845cf8ae09329240738c7971ef3db394f2ea45b3d3e2d20706559f972b49ea8021243fdf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/299dbd9b60ff40c0413ae8b66fed60aedaf17c55774cf8cc497a2f4b5662ce0e4e072e9d83ddf8d86ffb8dc7a11e07d088693db94d62092ecb5ab2f0b10ec068", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5602971298fd7ff49e17d5d5c3977955e2630e1ecd954230b6a4627dbb4f12592306233d5c1556237f75d0b888ff70d33681846a436bc940e67f0a7a35cd20a9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 20:43:28.980343) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 20:43:28.980664) ========= 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.SyKeVZuWL1/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.1zwvsDaAlw/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005098 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 20:43:29.029138) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 20:43:29.029453) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 20:43:29.029785) ========= ========= Total master_lag: 0.24 =========