builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0102 starttime: 1463144733.46 results: success (0) buildid: 20160513030746 builduid: 8342543e907a4c2ab97c97965526f4e8 revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:33.461700) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:33.462203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-05-13 06:05:33.462519) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-05-13 06:05:36.508943) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:36.509375) ========= 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.898C1JXecE/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.9hIFVMuY6k/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-13 06:05:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 22.6M=0.001s 2016-05-13 06:05:37 (22.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.495412 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:37.022843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:37.023335) ========= 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.898C1JXecE/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.9hIFVMuY6k/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.027359 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:37.093691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 06:05:37.094094) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --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 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.898C1JXecE/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.9hIFVMuY6k/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-13 06:05:37,172 truncating revision to first 12 chars 2016-05-13 06:05:37,172 Setting DEBUG logging. 2016-05-13 06:05:37,173 attempt 1/10 2016-05-13 06:05:37,173 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5a2deb5a9b09?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 06:05:38,398 unpacking tar archive at: fx-team-5a2deb5a9b09/testing/mozharness/ program finished with exit code 0 elapsedTime=1.499893 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 06:05:38.611357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:38.611668) ========= script_repo_revision: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:38.612076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:38.612370) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 06:05:38.627169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-13 06:05:38.627462) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.898C1JXecE/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.9hIFVMuY6k/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:05:38 INFO - MultiFileLogger online at 20160513 06:05:38 in /builds/slave/test 06:05:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:05:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:05:38 INFO - {'append_to_log': False, 06:05:38 INFO - 'base_work_dir': '/builds/slave/test', 06:05:38 INFO - 'blob_upload_branch': 'Fx-Team', 06:05:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:05:38 INFO - 'branch': 'Fx-Team', 06:05:38 INFO - 'buildbot_json_path': 'buildprops.json', 06:05:38 INFO - 'config_files': ('talos/mac_config.py',), 06:05:38 INFO - 'default_actions': ('clobber', 06:05:38 INFO - 'read-buildbot-config', 06:05:38 INFO - 'download-and-extract', 06:05:38 INFO - 'populate-webroot', 06:05:38 INFO - 'create-virtualenv', 06:05:38 INFO - 'install', 06:05:38 INFO - 'run-tests'), 06:05:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:05:38 INFO - 'download_minidump_stackwalk': True, 06:05:38 INFO - 'download_symbols': 'ondemand', 06:05:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:05:38 INFO - 'tooltool.py': '/tools/tooltool.py', 06:05:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:05:38 INFO - '/tools/misc-python/virtualenv.py')}, 06:05:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:05:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:05:38 INFO - 'installer_path': 'installer.exe', 06:05:38 INFO - 'log_level': 'info', 06:05:38 INFO - 'log_name': 'talos', 06:05:38 INFO - 'log_to_console': True, 06:05:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:05:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:05:38 INFO - 'opt_config_files': (), 06:05:38 INFO - 'pip_index': False, 06:05:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:05:38 INFO - 'cmd': ('bash', 06:05:38 INFO - '-c', 06:05:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:05:38 INFO - 'enabled': True, 06:05:38 INFO - 'halt_on_failure': False, 06:05:38 INFO - 'name': 'check_screen_resolution'},), 06:05:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:05:38 INFO - 'cmd': ('bash', 06:05:38 INFO - '-c', 06:05:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:05:38 INFO - 'enabled': True, 06:05:38 INFO - 'halt_on_failure': False, 06:05:38 INFO - 'name': 'check_screen_resolution'},), 06:05:38 INFO - 'run_cmd_checks_enabled': True, 06:05:38 INFO - 'sps_profile': False, 06:05:38 INFO - 'sps_profile_interval': 0, 06:05:38 INFO - 'suite': 'dromaeojs-e10s', 06:05:38 INFO - 'system_bits': '32', 06:05:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:05:38 INFO - 'title': 't-yosemite-r7-0102', 06:05:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:05:38 INFO - 'use_talos_json': True, 06:05:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:05:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:05:38 INFO - 'work_dir': 'build'} 06:05:38 INFO - ##### 06:05:38 INFO - ##### Running clobber step. 06:05:38 INFO - ##### 06:05:38 INFO - Running pre-action listener: _resource_record_pre_action 06:05:38 INFO - Running main action method: clobber 06:05:38 INFO - rmtree: /builds/slave/test/build 06:05:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:05:40 INFO - Running post-action listener: _resource_record_post_action 06:05:40 INFO - ##### 06:05:40 INFO - ##### Finished clobber step (success) 06:05:40 INFO - ##### 06:05:40 INFO - ##### 06:05:40 INFO - ##### Running read-buildbot-config step. 06:05:40 INFO - ##### 06:05:40 INFO - Running pre-action listener: _resource_record_pre_action 06:05:40 INFO - Running main action method: read_buildbot_config 06:05:40 INFO - Using buildbot properties: 06:05:40 INFO - { 06:05:40 INFO - "project": "", 06:05:40 INFO - "product": "firefox", 06:05:40 INFO - "script_repo_revision": "production", 06:05:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 06:05:40 INFO - "repository": "", 06:05:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 06:05:40 INFO - "buildid": "20160513030746", 06:05:40 INFO - "pgo_build": "False", 06:05:40 INFO - "basedir": "/builds/slave/test", 06:05:40 INFO - "buildnumber": 8, 06:05:40 INFO - "slavename": "t-yosemite-r7-0102", 06:05:40 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:05:40 INFO - "platform": "yosemite_r7", 06:05:40 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 06:05:40 INFO - "branch": "fx-team", 06:05:40 INFO - "repo_path": "integration/fx-team", 06:05:40 INFO - "revision": "5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4", 06:05:40 INFO - "stage_platform": "macosx64", 06:05:40 INFO - "builduid": "8342543e907a4c2ab97c97965526f4e8", 06:05:40 INFO - "slavebuilddir": "test" 06:05:40 INFO - } 06:05:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:05:40 INFO - Running post-action listener: _resource_record_post_action 06:05:40 INFO - ##### 06:05:40 INFO - ##### Finished read-buildbot-config step (success) 06:05:40 INFO - ##### 06:05:40 INFO - ##### 06:05:40 INFO - ##### Running download-and-extract step. 06:05:40 INFO - ##### 06:05:40 INFO - Running pre-action listener: _resource_record_pre_action 06:05:40 INFO - Running main action method: download_and_extract 06:05:40 INFO - mkdir: /builds/slave/test/build/tests 06:05: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')]} 06:05:40 INFO - https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:05:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:05:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:05:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:42 INFO - Downloaded 1199 bytes. 06:05:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:05:42 INFO - Using the following test package requirements: 06:05:42 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:05:42 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:05:42 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:05:42 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'jsshell-mac.zip'], 06:05:42 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:05:42 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:05:42 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:05:42 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:05:42 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:05:42 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:05:42 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:05:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:05:42 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')]} 06:05:42 INFO - https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:05:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:05:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:05:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:43 INFO - Downloaded 18153910 bytes. 06:05:43 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'] 06:05:43 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 06:05:46 INFO - Return code: 0 06:05:46 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 06:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:05:46 INFO - https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:05:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:05:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:05:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:55 INFO - Downloaded 18153910 bytes. 06:05:55 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'] 06:05:55 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 06:05:58 INFO - Return code: 0 06:05:58 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')]} 06:05:58 INFO - https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:05:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:05:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:05:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:05:59 INFO - Downloaded 10840239 bytes. 06:05:59 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'] 06:05:59 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 06:06:00 INFO - Return code: 0 06:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:00 INFO - https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:06:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:06:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:06:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:06:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OI-k6j9GQOeFe3gRz-SStg/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 06:06:03 INFO - Downloaded 110069839 bytes. 06:06:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:06:03 INFO - mkdir: /builds/slave/test/properties 06:06:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:06:03 INFO - Writing to file /builds/slave/test/properties/build_url 06:06:03 INFO - Contents: 06:06:03 INFO - build_url:https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:06:04 INFO - Running post-action listener: _resource_record_post_action 06:06:04 INFO - Running post-action listener: set_extra_try_arguments 06:06:04 INFO - ##### 06:06:04 INFO - ##### Finished download-and-extract step (success) 06:06:04 INFO - ##### 06:06:04 INFO - ##### 06:06:04 INFO - ##### Running populate-webroot step. 06:06:04 INFO - ##### 06:06:04 INFO - Running pre-action listener: _resource_record_pre_action 06:06:04 INFO - Running main action method: populate_webroot 06:06:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:06:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:06:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:06:04 INFO - '--tppagecycles', 06:06:04 INFO - '7'], 06:06:04 INFO - 'tests': ['tsvgm']}}, 06:06:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:06:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:06:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:06:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:06:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:06:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:06:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['damp', 'tps']}, 06:06:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'tests': ['damp', 'tps']}, 06:06:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['dromaeo_dom']}, 06:06:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:06:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['a11yr', 06:06:04 INFO - 'ts_paint', 06:06:04 INFO - 'tpaint', 06:06:04 INFO - 'sessionrestore', 06:06:04 INFO - 'sessionrestore_no_auto_restore', 06:06:04 INFO - 'tabpaint']}, 06:06:04 INFO - 'other-e10s': {'tests': ['a11yr', 06:06:04 INFO - 'ts_paint', 06:06:04 INFO - 'tpaint', 06:06:04 INFO - 'sessionrestore', 06:06:04 INFO - 'sessionrestore_no_auto_restore', 06:06:04 INFO - 'tabpaint']}, 06:06:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:06:04 INFO - 'ts_paint', 06:06:04 INFO - 'tpaint', 06:06:04 INFO - 'sessionrestore', 06:06:04 INFO - 'sessionrestore_no_auto_restore', 06:06:04 INFO - 'tabpaint']}, 06:06:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:06:04 INFO - 'ts_paint', 06:06:04 INFO - 'tpaint', 06:06:04 INFO - 'sessionrestore', 06:06:04 INFO - 'sessionrestore_no_auto_restore', 06:06:04 INFO - 'tabpaint']}, 06:06:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['a11yr', 06:06:04 INFO - 'ts_paint', 06:06:04 INFO - 'tpaint', 06:06:04 INFO - 'sessionrestore', 06:06:04 INFO - 'sessionrestore_no_auto_restore', 06:06:04 INFO - 'tabpaint']}, 06:06:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['a11yr', 06:06:04 INFO - 'ts_paint', 06:06:04 INFO - 'tpaint', 06:06:04 INFO - 'sessionrestore', 06:06:04 INFO - 'sessionrestore_no_auto_restore', 06:06:04 INFO - 'tabpaint']}, 06:06:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['tsvgx', 06:06:04 INFO - 'tsvgr_opacity', 06:06:04 INFO - 'tart', 06:06:04 INFO - 'tscrollx', 06:06:04 INFO - 'cart']}, 06:06:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:06:04 INFO - 'tsvgr_opacity', 06:06:04 INFO - 'tart', 06:06:04 INFO - 'tscrollx', 06:06:04 INFO - 'cart']}, 06:06:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'talos_options': ['--disable-e10s'], 06:06:04 INFO - 'tests': ['tp5o']}, 06:06:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'tests': ['tp5o']}, 06:06:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'talos_options': ['--disable-e10s', 06:06:04 INFO - '--xperf_path', 06:06:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:06:04 INFO - 'tests': ['tp5n']}, 06:06:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:06:04 INFO - 'talos_options': ['--xperf_path', 06:06:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:06:04 INFO - 'tests': ['tp5n']}}, 06:06:04 INFO - 'talos.zip': {'path': '', 06:06:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:06:04 INFO - Running post-action listener: _resource_record_post_action 06:06:04 INFO - ##### 06:06:04 INFO - ##### Finished populate-webroot step (success) 06:06:04 INFO - ##### 06:06:04 INFO - ##### 06:06:04 INFO - ##### Running create-virtualenv step. 06:06:04 INFO - ##### 06:06:04 INFO - Running pre-action listener: _resource_record_pre_action 06:06:04 INFO - Running main action method: create_virtualenv 06:06:04 INFO - Creating virtualenv /builds/slave/test/build/venv 06:06:04 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 06:06:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:06:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:06:04 INFO - Using real prefix '/tools/python27' 06:06:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:06:05 INFO - Installing distribute.............................................................................................................................................................................................done. 06:06:08 INFO - Installing pip.................done. 06:06:08 INFO - Return code: 0 06:06:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:08 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:08 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 06:06:08 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 06:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:08 INFO - 'HOME': '/Users/cltbld', 06:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:08 INFO - 'LOGNAME': 'cltbld', 06:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:08 INFO - 'MOZ_NO_REMOTE': '1', 06:06:08 INFO - 'NO_EM_RESTART': '1', 06:06:08 INFO - 'PAGER': '/bin/cat', 06:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:08 INFO - 'PWD': '/builds/slave/test', 06:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:08 INFO - 'SHELL': '/bin/bash', 06:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:08 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:08 INFO - 'USER': 'cltbld', 06:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:08 INFO - 'XPC_FLAGS': '0x0', 06:06:08 INFO - 'XPC_SERVICE_NAME': '0', 06:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:08 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 06:06:08 INFO - Cleaning up... 06:06:08 INFO - Return code: 0 06:06:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:08 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:08 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 06:06:08 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 06:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:08 INFO - 'HOME': '/Users/cltbld', 06:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:08 INFO - 'LOGNAME': 'cltbld', 06:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:08 INFO - 'MOZ_NO_REMOTE': '1', 06:06:08 INFO - 'NO_EM_RESTART': '1', 06:06:08 INFO - 'PAGER': '/bin/cat', 06:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:08 INFO - 'PWD': '/builds/slave/test', 06:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:08 INFO - 'SHELL': '/bin/bash', 06:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:08 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:08 INFO - 'USER': 'cltbld', 06:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:08 INFO - 'XPC_FLAGS': '0x0', 06:06:08 INFO - 'XPC_SERVICE_NAME': '0', 06:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:08 INFO - Downloading/unpacking psutil>=3.1.1 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:06:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:11 INFO - Installing collected packages: psutil 06:06:11 INFO - Running setup.py install for psutil 06:06:11 INFO - building 'psutil._psutil_osx' extension 06:06:11 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 06:06:12 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 06:06:12 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 06:06:12 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 06:06:12 INFO - building 'psutil._psutil_posix' extension 06:06:12 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 06:06:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:06:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:06:12 INFO - ^ 06:06:12 INFO - 1 warning generated. 06:06:12 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 06:06:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:12 INFO - Successfully installed psutil 06:06:12 INFO - Cleaning up... 06:06:12 INFO - Return code: 0 06:06:12 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 06:06:12 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')]} 06:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:12 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')]} 06:06:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 06:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 06:06:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:12 INFO - 'HOME': '/Users/cltbld', 06:06:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:12 INFO - 'LOGNAME': 'cltbld', 06:06:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:12 INFO - 'MOZ_NO_REMOTE': '1', 06:06:12 INFO - 'NO_EM_RESTART': '1', 06:06:12 INFO - 'PAGER': '/bin/cat', 06:06:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:12 INFO - 'PWD': '/builds/slave/test', 06:06:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:12 INFO - 'SHELL': '/bin/bash', 06:06:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:12 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:12 INFO - 'USER': 'cltbld', 06:06:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:12 INFO - 'XPC_FLAGS': '0x0', 06:06:12 INFO - 'XPC_SERVICE_NAME': '0', 06:06:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:13 INFO - Downloading/unpacking mozsystemmonitor==0.1 06:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:15 INFO - Downloading mozsystemmonitor-0.1.tar.gz 06:06:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 06:06:15 INFO - Installing collected packages: mozsystemmonitor 06:06:15 INFO - Running setup.py install for mozsystemmonitor 06:06:15 INFO - Successfully installed mozsystemmonitor 06:06:15 INFO - Cleaning up... 06:06:15 INFO - Return code: 0 06:06:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:06:15 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')]} 06:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:15 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')]} 06:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:15 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:15 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 06:06:15 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 06:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:15 INFO - 'HOME': '/Users/cltbld', 06:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:15 INFO - 'LOGNAME': 'cltbld', 06:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:15 INFO - 'MOZ_NO_REMOTE': '1', 06:06:15 INFO - 'NO_EM_RESTART': '1', 06:06:15 INFO - 'PAGER': '/bin/cat', 06:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:15 INFO - 'PWD': '/builds/slave/test', 06:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:15 INFO - 'SHELL': '/bin/bash', 06:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:15 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:15 INFO - 'USER': 'cltbld', 06:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:15 INFO - 'XPC_FLAGS': '0x0', 06:06:15 INFO - 'XPC_SERVICE_NAME': '0', 06:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:15 INFO - Downloading/unpacking blobuploader==1.2.4 06:06:15 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 06:06:15 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 06:06:15 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 06:06:15 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 06:06:15 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 06:06:15 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 06:06:18 INFO - Downloading blobuploader-1.2.4.tar.gz 06:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:06:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:06:18 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 06:06:18 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 06:06:18 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 06:06:18 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 06:06:18 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 06:06:18 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 06:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:06:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:19 INFO - Downloading docopt-0.6.1.tar.gz 06:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:06:19 INFO - Installing collected packages: blobuploader, requests, docopt 06:06:19 INFO - Running setup.py install for blobuploader 06:06:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:06:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:06:19 INFO - Running setup.py install for requests 06:06:20 INFO - Running setup.py install for docopt 06:06:20 INFO - Successfully installed blobuploader requests docopt 06:06:20 INFO - Cleaning up... 06:06:20 INFO - Return code: 0 06:06:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:20 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:20 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 06:06:20 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 06:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:20 INFO - 'HOME': '/Users/cltbld', 06:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:20 INFO - 'LOGNAME': 'cltbld', 06:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:20 INFO - 'MOZ_NO_REMOTE': '1', 06:06:20 INFO - 'NO_EM_RESTART': '1', 06:06:20 INFO - 'PAGER': '/bin/cat', 06:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:20 INFO - 'PWD': '/builds/slave/test', 06:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:20 INFO - 'SHELL': '/bin/bash', 06:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:20 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:20 INFO - 'USER': 'cltbld', 06:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:20 INFO - 'XPC_FLAGS': '0x0', 06:06:20 INFO - 'XPC_SERVICE_NAME': '0', 06:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:06:20 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-f8b6Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:06:20 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-Jselq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:06:20 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-oVnQT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-v1pOB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-fBnJns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-rqVVbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-CVjCBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-cbWZ0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-AD1ULC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-HV2eF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-ymxgrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:06:21 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-NKL6Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:06:22 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-mNseyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:06:22 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-TdIpyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:22 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-E_08aI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:06:22 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-A1X9jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:06:22 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-S2Uubj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:06:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:06:22 INFO - Running setup.py install for manifestparser 06:06:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:06:22 INFO - Running setup.py install for mozcrash 06:06:22 INFO - Running setup.py install for mozdebug 06:06:22 INFO - Running setup.py install for mozdevice 06:06:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Running setup.py install for mozfile 06:06:23 INFO - Running setup.py install for mozhttpd 06:06:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Running setup.py install for mozinfo 06:06:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Running setup.py install for mozInstall 06:06:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:06:23 INFO - Running setup.py install for mozleak 06:06:23 INFO - Running setup.py install for mozlog 06:06:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:06:24 INFO - Running setup.py install for moznetwork 06:06:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:06:24 INFO - Running setup.py install for mozprocess 06:06:24 INFO - Running setup.py install for mozprofile 06:06:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:06:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:06:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:06:24 INFO - Running setup.py install for mozrunner 06:06:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:06:24 INFO - Running setup.py install for mozscreenshot 06:06:24 INFO - Running setup.py install for moztest 06:06:24 INFO - Running setup.py install for mozversion 06:06:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:06:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:06:25 INFO - Cleaning up... 06:06:25 INFO - Return code: 0 06:06:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:25 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:25 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 06:06:25 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 06:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:25 INFO - 'HOME': '/Users/cltbld', 06:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:25 INFO - 'LOGNAME': 'cltbld', 06:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:25 INFO - 'MOZ_NO_REMOTE': '1', 06:06:25 INFO - 'NO_EM_RESTART': '1', 06:06:25 INFO - 'PAGER': '/bin/cat', 06:06:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:25 INFO - 'PWD': '/builds/slave/test', 06:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:25 INFO - 'SHELL': '/bin/bash', 06:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:25 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:25 INFO - 'USER': 'cltbld', 06:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:25 INFO - 'XPC_FLAGS': '0x0', 06:06:25 INFO - 'XPC_SERVICE_NAME': '0', 06:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:06:25 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-RWll1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:06:25 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)) 06:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:06:25 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-H3Uv94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:06:25 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)) 06:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:06:25 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-iQgOo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:06:25 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)) 06:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:06:25 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-q2dy4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:06:25 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)) 06:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:06:25 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-sRzyNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:06:25 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)) 06:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:06:25 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-cQi4Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-X_lcUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-hz4rnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-tKZhHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-tqEkO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-1muUiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-BJrY1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-YO_8Kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-b1FesH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:06:26 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)) 06:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:26 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-MdTjoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:27 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)) 06:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:06:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-DDOERQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:06:27 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)) 06:06:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:06:27 INFO - Running setup.py (path:/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/pip-dDmeiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:06:27 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)) 06:06:27 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)) 06:06:27 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)) 06:06:27 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)) 06:06:27 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)) 06:06:27 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)) 06:06:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:06:27 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 06:06:27 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 06:06:27 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 06:06:27 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 06:06:27 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 06:06:27 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 06:06:29 INFO - Downloading blessings-1.6.tar.gz 06:06:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:06:29 INFO - Installing collected packages: blessings 06:06:29 INFO - Running setup.py install for blessings 06:06:29 INFO - Successfully installed blessings 06:06:29 INFO - Cleaning up... 06:06:30 INFO - Return code: 0 06:06:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:06:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:06:30 INFO - Reading from file tmpfile_stdout 06:06:30 INFO - Current package versions: 06:06:30 INFO - blessings == 1.6 06:06:30 INFO - blobuploader == 1.2.4 06:06:30 INFO - docopt == 0.6.1 06:06:30 INFO - manifestparser == 1.1 06:06:30 INFO - mozInstall == 1.12 06:06:30 INFO - mozcrash == 0.17 06:06:30 INFO - mozdebug == 0.1 06:06:30 INFO - mozdevice == 0.48 06:06:30 INFO - mozfile == 1.2 06:06:30 INFO - mozhttpd == 0.7 06:06:30 INFO - mozinfo == 0.9 06:06:30 INFO - mozleak == 0.1 06:06:30 INFO - mozlog == 3.2 06:06:30 INFO - moznetwork == 0.27 06:06:30 INFO - mozprocess == 0.22 06:06:30 INFO - mozprofile == 0.28 06:06:30 INFO - mozrunner == 6.11 06:06:30 INFO - mozscreenshot == 0.1 06:06:30 INFO - mozsystemmonitor == 0.1 06:06:30 INFO - moztest == 0.7 06:06:30 INFO - mozversion == 1.4 06:06:30 INFO - psutil == 3.1.1 06:06:30 INFO - requests == 1.2.3 06:06:30 INFO - wsgiref == 0.1.2 06:06:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:30 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:30 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 06:06:30 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 06:06:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:30 INFO - 'HOME': '/Users/cltbld', 06:06:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:30 INFO - 'LOGNAME': 'cltbld', 06:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:30 INFO - 'MOZ_NO_REMOTE': '1', 06:06:30 INFO - 'NO_EM_RESTART': '1', 06:06:30 INFO - 'PAGER': '/bin/cat', 06:06:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:30 INFO - 'PWD': '/builds/slave/test', 06:06:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:30 INFO - 'SHELL': '/bin/bash', 06:06:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:30 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:30 INFO - 'USER': 'cltbld', 06:06:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:30 INFO - 'XPC_FLAGS': '0x0', 06:06:30 INFO - 'XPC_SERVICE_NAME': '0', 06:06:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 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)) 06:06:30 INFO - Cleaning up... 06:06:30 INFO - Return code: 0 06:06:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:30 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:30 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 06:06:30 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 06:06:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:30 INFO - 'HOME': '/Users/cltbld', 06:06:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:30 INFO - 'LOGNAME': 'cltbld', 06:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:30 INFO - 'MOZ_NO_REMOTE': '1', 06:06:30 INFO - 'NO_EM_RESTART': '1', 06:06:30 INFO - 'PAGER': '/bin/cat', 06:06:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:30 INFO - 'PWD': '/builds/slave/test', 06:06:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:30 INFO - 'SHELL': '/bin/bash', 06:06:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:30 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:30 INFO - 'USER': 'cltbld', 06:06:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:30 INFO - 'XPC_FLAGS': '0x0', 06:06:30 INFO - 'XPC_SERVICE_NAME': '0', 06:06:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:30 INFO - Downloading/unpacking jsonschema 06:06:30 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 06:06:30 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 06:06:30 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 06:06:30 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 06:06:30 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 06:06:30 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 06:06:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:06:33 INFO - Downloading/unpacking functools32 (from jsonschema) 06:06:33 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 06:06:33 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 06:06:33 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 06:06:33 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 06:06:33 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 06:06:33 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 06:06:33 INFO - Downloading functools32-3.2.3-2.tar.gz 06:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:06:33 INFO - warning: no files found matching '*.txt' 06:06:33 INFO - no previously-included directories found matching 'build' 06:06:33 INFO - no previously-included directories found matching 'dist' 06:06:33 INFO - no previously-included directories found matching '.git*' 06:06:33 INFO - Installing collected packages: jsonschema, functools32 06:06:33 INFO - Running setup.py install for functools32 06:06:33 INFO - warning: no files found matching '*.txt' 06:06:33 INFO - no previously-included directories found matching 'build' 06:06:33 INFO - no previously-included directories found matching 'dist' 06:06:33 INFO - no previously-included directories found matching '.git*' 06:06:33 INFO - Successfully installed jsonschema functools32 06:06:33 INFO - Cleaning up... 06:06:34 INFO - Return code: 0 06:06:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:06:34 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')]} 06:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:34 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')]} 06:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:06:34 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 0x10877ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108781570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa352558d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, '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.898C1JXecE/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.9hIFVMuY6k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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 06:06:34 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 06:06:34 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 06:06:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:06:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:34 INFO - 'HOME': '/Users/cltbld', 06:06:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:34 INFO - 'LOGNAME': 'cltbld', 06:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:34 INFO - 'MOZ_NO_REMOTE': '1', 06:06:34 INFO - 'NO_EM_RESTART': '1', 06:06:34 INFO - 'PAGER': '/bin/cat', 06:06:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:34 INFO - 'PWD': '/builds/slave/test', 06:06:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:34 INFO - 'SHELL': '/bin/bash', 06:06:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:06:34 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:06:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:34 INFO - 'USER': 'cltbld', 06:06:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:34 INFO - 'XPC_FLAGS': '0x0', 06:06:34 INFO - 'XPC_SERVICE_NAME': '0', 06:06:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:34 INFO - Downloading/unpacking flake8 06:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:06:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:06:36 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 06:06:36 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 06:06:36 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 06:06:36 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 06:06:36 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 06:06:36 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 06:06:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:06:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 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 06:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:06:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:06:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:06:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:06:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:06:37 INFO - no previously-included directories found matching 'docs/_build' 06:06:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:06:38 INFO - Running setup.py install for pep8 06:06:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:06:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:06:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:06:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:06:38 INFO - no previously-included directories found matching 'docs/_build' 06:06:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:06:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:06:38 INFO - Cleaning up... 06:06:38 INFO - Return code: 0 06:06:38 INFO - Running post-action listener: _resource_record_post_action 06:06:38 INFO - Running post-action listener: _start_resource_monitoring 06:06:38 INFO - Starting resource monitoring. 06:06:38 INFO - ##### 06:06:38 INFO - ##### Finished create-virtualenv step (success) 06:06:38 INFO - ##### 06:06:38 INFO - ##### 06:06:38 INFO - ##### Running install step. 06:06:38 INFO - ##### 06:06:38 INFO - Running pre-action listener: _resource_record_pre_action 06:06:38 INFO - Running main action method: install 06:06:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:06:38 INFO - Reading from file tmpfile_stdout 06:06:38 INFO - Detecting whether we're running mozinstall >=1.0... 06:06:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:06:38 INFO - Reading from file tmpfile_stdout 06:06:38 INFO - Output received: 06:06:38 INFO - Usage: mozinstall [options] installer 06:06:38 INFO - Options: 06:06:38 INFO - -h, --help show this help message and exit 06:06:38 INFO - -d DEST, --destination=DEST 06:06:38 INFO - Directory to install application into. [default: 06:06:38 INFO - "/builds/slave/test"] 06:06:38 INFO - --app=APP Application being installed. [default: firefox] 06:06:38 INFO - mkdir: /builds/slave/test/build/application 06:06:38 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'] 06:06:38 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 06:07:12 INFO - Reading from file tmpfile_stdout 06:07:12 INFO - Output received: 06:07:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:07:12 INFO - Running post-action listener: _resource_record_post_action 06:07:12 INFO - ##### 06:07:12 INFO - ##### Finished install step (success) 06:07:12 INFO - ##### 06:07:12 INFO - ##### 06:07:12 INFO - ##### Running run-tests step. 06:07:12 INFO - ##### 06:07:12 INFO - Running pre-action listener: _resource_record_pre_action 06:07:12 INFO - Running main action method: run_tests 06:07:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:07:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:07:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:07:12 INFO - 2016-05-13 06:07:12.164 screenresolution[1645:8518] starting screenresolution argv=screenresolution get 06:07:12 INFO - 2016-05-13 06:07:12.166 screenresolution[1645:8518] Display 0: 1600x1200x32@60 06:07:12 INFO - 2016-05-13 06:07:12.172 screenresolution[1646:8520] starting screenresolution argv=screenresolution list 06:07:12 INFO - Available Modes on Display 0 06:07:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:07:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:07:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:07:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:07:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:07:12 INFO - Intel Iris: 06:07:12 INFO - Chipset Model: Intel Iris 06:07:12 INFO - Type: GPU 06:07:12 INFO - Bus: Built-In 06:07:12 INFO - VRAM (Dynamic, Max): 1536 MB 06:07:12 INFO - Vendor: Intel (0x8086) 06:07:12 INFO - Device ID: 0x0a2e 06:07:12 INFO - Revision ID: 0x0009 06:07:12 INFO - Displays: 06:07:12 INFO - Display: 06:07:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:07:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:07:12 INFO - Display Serial Number: 1600x1200 60 06:07:12 INFO - Main Display: Yes 06:07:12 INFO - Mirror: Off 06:07:12 INFO - Online: Yes 06:07:12 INFO - Rotation: Supported 06:07:12 INFO - Return code: 0 06:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:07:12 INFO - Python 2.7.3 06:07:12 INFO - Return code: 0 06:07:12 INFO - grabbing minidump binary from tooltool 06:07:12 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')]} 06:07:12 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 0x10861fd30>, '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 0x108443db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108616b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:07:12 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 06:07:12 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 06:07:12 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 06:07:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:07:12 INFO - Return code: 0 06:07:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:07:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:07:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:07:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:07:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:07:13 INFO - Return code: 0 06:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0102 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.898C1JXecE/Render', 06:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:13 INFO - 'HOME': '/Users/cltbld', 06:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:13 INFO - 'LOGNAME': 'cltbld', 06:07:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:07:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:13 INFO - 'MOZ_NO_REMOTE': '1', 06:07:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:07:13 INFO - 'NO_EM_RESTART': '1', 06:07:13 INFO - 'PAGER': '/bin/cat', 06:07:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:13 INFO - 'PWD': '/builds/slave/test', 06:07:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:13 INFO - 'SHELL': '/bin/bash', 06:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9hIFVMuY6k/Listeners', 06:07:13 INFO - 'TMPDIR': '/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/', 06:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:13 INFO - 'USER': 'cltbld', 06:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:13 INFO - 'XPC_FLAGS': '0x0', 06:07:13 INFO - 'XPC_SERVICE_NAME': '0', 06:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0102', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:07:13 INFO - mozversion application_buildid: 20160513030746 06:07:13 INFO - mozversion application_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 06:07:13 INFO - mozversion application_display_name: Nightly 06:07:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:07:13 INFO - mozversion application_name: Firefox 06:07:13 INFO - mozversion application_remotingname: firefox 06:07:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:07:13 INFO - mozversion application_vendor: Mozilla 06:07:13 INFO - mozversion application_version: 49.0a1 06:07:13 INFO - mozversion platform_buildid: 20160513030746 06:07:13 INFO - mozversion platform_changeset: 5a2deb5a9b09df9ad2ca3ac1d0c480be250d31e4 06:07:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:07:13 INFO - mozversion platform_version: 49.0a1 06:07:13 INFO - using testdate: 1463144833 06:07:13 INFO - actual date: 1463144833 06:07:13 INFO - starting webserver on 'localhost:49275' 06:07:13 INFO - SUITE-START | Running 2 tests 06:07:13 INFO - TEST-START | dromaeo_css 06:07:13 INFO - operating with platform_type : mac_ 06:07:13 INFO - Initialising browser for dromaeo_css test... 06:07:13 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpKjsszs/profile http://localhost:49275/getInfo.html) 06:07:16 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:07:16 INFO - PROCESS | 1664 | colorDepth:24 06:07:16 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:07:16 INFO - PROCESS | 1664 | __metrics 06:07:16 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 06:07:17 INFO - TEST-INFO | 1664: exit 0 06:07:17 INFO - Browser initialized. 06:07:17 INFO - Running cycle 1/1 for dromaeo_css test... 06:07:17 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpKjsszs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:29 INFO - PROCESS | 1668 | RSS: Main: 249978880 06:07:29 INFO - PROCESS | 1668 | 06:08:19 INFO - PROCESS | 1668 | 1463144899218 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] 06:10:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 06:10:30 INFO - PROCESS | 1668 | RSS: Main: 262049792 06:10:30 INFO - PROCESS | 1668 | 06:13:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 06:13:34 INFO - PROCESS | 1668 | RSS: Main: 273616896 06:13:34 INFO - PROCESS | 1668 | 06:16:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 06:16:36 INFO - PROCESS | 1668 | RSS: Main: 275701760 06:16:36 INFO - PROCESS | 1668 | 06:19:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 06:19:38 INFO - PROCESS | 1668 | RSS: Main: 277250048 06:19:38 INFO - PROCESS | 1668 | 06:22:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:22:42 INFO - PROCESS | 1668 | RSS: Main: 280141824 06:22:42 INFO - PROCESS | 1668 | 06:25:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:25:45 INFO - PROCESS | 1668 | RSS: Main: 281268224 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | __start_tp_report 06:25:45 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 06:25:45 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 06:25:45 INFO - PROCESS | 1668 | |i|pagename|runs| 06:25:45 INFO - PROCESS | 1668 | |0;dojo.html;3472.4964880092402;3511.069566564861;3511.806850623215;3528.682418582328;3530.329237444887;4975.751212439377;5833.508198687008;5930.792422265216;5939.703014849251;5981.910271345933;5746.655200687962;5868.119782032697;5886.499647529965;5982.8803423931495;6039.547033972448;5273.446288139738;5276.34045744282;5288.603354748409;5344.385395679488;5389.299391079174;175.4933219671444;5216.921746173811;5259.658122222062;5313.335833020872;5337.412884582693;5258.8685282867855;5265.842024739264;5272.8154514592;5289.074411977907;5320.308359913187;5289.68261904287;5316.282301889234;5318.095923692981;5331.813386531453;5341.946580534183;5169.198774190007;5249.790008399659;5298.284731561267;5298.417174110883;5325.627206095575;5697.829065128053;5811.82564523065;5823.941760582382;5847.8538036549;5888.234529511179;5123.923141152848;6155.353687862799;6256.593321434138;6285.685715714196;6300.369963003709;6195.5663103582765;6243.937560624381;6267.8746425071695;6299.779507717208;6308.463780578656;5965.3139888912465;5997.909095434867;5999.730012149464;6016.819495415145;6041.426064523863;6206.751729930752;6224.968875155595;6291.874162516724;6316.178896743394;6351.2060992375955;6186.226721659793;6220.191375121205;6227.1282020517165;6236.18929539166;6256.562040657111;6228.532860035515;6277.466415354746;6313.652749098844;6319.494440444753;6333.588316759396;5228.869278268074;6122.693865306717;6241.812745617548;6254.655993920287;6288.119663247149;6249.437550620466;6257.155284036597;6263.31103578606;6268.655223962634;6275.937240627627;4997.275395067768;4999.900001999939;5010.599152067826;5013.147764879979;5020.096382651158;4407.4050003249795;4455.554444555583;4460.77696115193;4527.932081018787;4541.500434952219;4926.950730492721;5048.3184770056305;5817.214676018768;5841.036229022248;6079.544034197453;6281.685915704196;6306.432421082125;6310.5898116622275;6322.051692246107;6348.746050158034;5883.14694369314;6014.1580178775;6029;6059.242594675666;6082.847928801815;5504.91742623861;5514.338279406497;5519.116941289374;5532.585056120807;5538;6480.31956644555;6500;6518.869622607522;6534.673266336759;6609.603423794588;5180.9740951295;6221.3156552780065;6231.2834024086;6240.4071613196675;6319.52603554726;6447.6453795041725;6452.9677351612945;6492.480601551959;6498.740050397932;6533.444657204191;6133.44798968095;6264.74941002373;6273.153124328157;6280.27776805655;6284.560080794484;6141.424638644238;6168.444839964424;6206.875862482635;6298.905516417166;6317.778877739441;6101.542384321282;6162.352952939877;6176.227971503562;6195.132681424514;6197.783077592263;6057.030875059969;6107.480864126599;6118.326984031664;6174.197354343906;6190.907136392868;5983.931942362131;6106.582013105306;6183.412575805291;6189.6286222826775;6195.2255968004;6167.722452489559;6200;6201.627902325875;6214;6223.688815559294;6012.579119461596;6098;6115.1438798569125;6185.907211391743;6188.412100850412 06:25:45 INFO - PROCESS | 1668 | |1;ext.html;501.81432869838164;522.5140619224122;552.6242155334373;565.5927732032936;592.2034863109117;34584;39696.40455393168;41464.79267603662;41532.58467415325;41765.79117104418;42483.36274955878;43190.568094319045;43283.56716432835;43409.78295108528;43459.34810977838;8531.658733650678;9479.85780213299;11843.940780296087;12081.818772718416;12096.092793040558;11768.882311176907;11904.702382440422;11931.880681193206;11962.401879906014;12048.156629035971;12014.219075760091;12060.939695301555;12072.456739446747;12073.758524829498;12151.149419540636;11774;11928.224665396721;11971.2817230966;12020.278783273032;12021.819672704916;8732.301415886714;11702.707432314175;11914.225575337576;11933.641990740292;12022;42559.361609575884;43308.350374744405;43333.78333108314;44979.775101124615;45084;3205;3322.3355328934067;3524.4713293006;3659.249853779969;4107;29457;30925.536116958265;31698.683013169804;31744.841275793475;31855.52216716751;2659.7606215440705;2731.112388473754;2859.3280579063903;2871.267826704198;2874.238326843388;555127;559525.8213380408;561916;565527.1723641397;565992;72939;73229.6338518304;73767;73953.26046739571;74257;76264;76706;77270;77273.45453090906;77358;13643.795343069862;13911.373988170355;17381.52257058164;17802;17883.374081907332;11632.592859250055;13439;13450.26023568713;13454.865451345557;13463.461461541428;18110.90944545269;20915.79084209169;21809.127634894743;21843;21849;8526;9093;9110.681126160553;9125.22435592982;9177.541122943827;957.5355952363099;1079.519613771864;1128.8645362556547;1144.4849817582121;1160.4835848047537;30722.539161912588;30858.69141308603;31031.518338214555;31148.598313075967;31192.376152476823;1037.8080055189814;1073.5276478349501;1080.621782376162;1085.696005118568;1110.0564520157802;15846.366145354288;15907.284172212047;15942.920285398499;16008;16302.83697163037;722748;724144;724355;735319;736189;64434.67782661057;64760;65020;65026;65166.67416662981;1436.1096120405207;1468.3172324868988;1471.4114354258168;1477.5641185850416;1497.6779992301708;25908.35229119287;26945.59581606238;27373;29307;30115.397692046474;56678.43321566732;56737.14894276507;57167.428325717876;57296.42703572911;57535;622927;626859;627801;629598;630788;43924.780376097915;43930.68207953766;44377.11245775131;44386.44647437324;45225.54774452213;48199.75900120477;48236.75881620569;48515.27227091526;49371;49977;39962;39982.60017399789;40212.59787402205;40383.192336152526;41246.38130428106;38321.23357532778;39026.60973390343;39416.60583394244;39448.605513944494;40399.59600403958;38489.422658660704;39405.80297098611;39497.42010319555;40072.39891401573;42035.15929681328;32132.518012230303;34186.974390768315;34791;34982.12544686403;34985;21979.461437699207;25796.226113216635;25968.22095337143;26066.220398041798;26212 06:25:45 INFO - PROCESS | 1668 | |2;jquery.html;368.4344531144692;394.686224451561;395.23324749985017;402.2959820314449;412.79154027216254;14909.478168264113;18826.49388048956;20250;21383.893080534595;21410;19688.212471501123;21333.57332853342;21374.465638359048;22454.775452245474;22486.55026899461;5153.845384638448;5511.366192887809;6005.459508644221;6029.397060293979;6031.6984150792505;6024.21685180928;6071.78748743794;6283.3088360280335;6841;6887.414569761576;5839.737211825478;6800.557963732366;6802.489813264;6806.3874251317375;6835.555688880207;5319.723266416052;6103.359147289537;6121.296050954153;6144.877102457948;6168.629882207082;5058.048071370234;5078.365204349457;5760.6255593386295;6015.819525414245;6037.426444487811;13268.601941941757;13742.244176570284;13763.174209547358;13930.164190148522;13946.442142314296;1933.9033048347667;2118.650422680256;2325.918592849242;2332.4635333873134;2397.11306816479;10044.497775111215;10447.164226861833;10462.424566648831;12905.838474537337;14915.776263356058;1541.6223025358747;1543.8070241219848;1787.5352408373917;1796.2276221224868;1865.2911893480525;99455.50272248665;132157.33921330332;135062;136481;141255.29372353177;9651.662191823252;11384.943075284571;11624;11703.122265830098;11948.22336548139;14847.331870065656;14847.554573362817;14866.628334291512;15198;15263.160526171167;8629.78425539354;8809.823803523894;8847.778805529784;8921.241694455914;8930.553472326357;8727.738167854974;8733.606987685569;8741.650333986569;8775.561221938877;8853.46879187238;4642.698224615457;4687.32033855096;4750.809967601326;4774.236122220428;4809.495003024632;1703.4208369154298;1739.6607661505884;1978.2284908885547;2030.9081645904741;2033.2172113736317;911.3620465674055;935.9906400936039;945.2438049560325;1012.3116280929039;1060.7825395793843;15225.771613425808;20072.598548028956;20414.897925510573;20741.27405540799;20938.162473501194;960.9279304052224;968.790604822047;969.7575606098476;991.4348821171862;1040.552562398176;8343.249107580346;10768.192385571114;10963.83554246687;10994.175436842268;11109.222354435273;585580;586790;599362;602171;602593;134036;135048.3247583756;136171;136790;143717;1000.7290740759195;1012.7670635753818;1026.486756621704;1028.6091285311684;1036.3212096077082;18379.816201838345;18585.34951276699;18625.696201265782;18785.436436906915;18892.433227003214;49834.50165498299;51003.48996509987;51727;51884.74057629688;52911.4708852922;76921;77551;79849;80257;80309;8227.218414250636;8262.586870656563;8330.416870818985;8442.873356899774;8680.392372533863;12318.260904345769;12965.15726477776;13025.348732563145;13200.273984930922;13505.98705097102;10765.838512422477;11094.722631934266;11202.383868886964;11216.270942388719;11439;10955.014048735651;11227.775444491228;11467.369294688539;11579;11768.70578235551;11310.4910279036;11672.47473863695;11805.586804461802;11848.822267666164;11933.164678472423;8356.456830306011;8460.196281353261;8540.35947303967;8542.44474109181;8697.913020869963;11035.282706624364;11418.371989540656;11474.598389056342;11551.133664975328;11790.882091179321 06:25:45 INFO - PROCESS | 1668 | |3;mootools.html;1425.921574313413;1550.720870243356;1755.3329734700812;1779.9911000444995;1838.0625880800796;6617.86764264715;6972.511924165297;8805.867911981326;9232.169104780569;9271.629134834599;7587.81030474237;8937.955310223457;9113.863292050624;9163.908360916388;9168.174864262215;2436.281297017384;2451.460678650694;2475.306914064056;2476.7151777545632;2486.0428734937;2104.231955336299;2437.2322718343667;2456.3981824452944;2494.8627825469594;2496.226169887341;1974.7926467720897;2351.1183306260154;2358.2335740884196;2373.679553260305;2382.7736365045293;2418.6734790803193;2421.031587365068;2442.206282958037;2451.1298489036312;2456.1403508771964;2361.7047869016374;2367.3844800351862;2383.0944241188413;2384.427737343025;2416.830821842472;9061.637534498677;9219.539023048821;9301.395409298442;9308.720738377859;9310.394824336396;5468.2891224141;5483.479069488392;5535.280413546213;5655.4061823508355;5657.9434205658235;3594.982025089858;3671.7796932183887;3709.2581483703366;3715.1269451678836;3722.4416337549314;1048.64346122318;1061.0185578340004;1068.4711068021302;1072.447689439939;1086.8261078227524;12228;12250.571230006995;12346.691332716664;12411.441485133191;12437.191582547106;4891.437484689235;4907.435644900811;5028.84913452597;5053.747312634354;5061.4179369373;10490.685279441628;10514.526846291936;10537.947310263553;10563.94718026405;10598.364098154136;5462.071447853875;5474.315710536183;5517.337919449611;5537.667739935617;5602.915956260659;4478.552144785495;4879.902401952012;4964.280179374043;5012.899742005139;5089.083964886356;3802.6767724743154;4086.6922584772587;4097.713160078825;4181.749095054246;4247.893802654959;1198.2151690642643;1341.6578772412972;1362.7751421015619;1366.4261010375667;1379.703363776793;866.2593482572436;876.3252295732251;878.5563290538237;904.0823564082461;957.095544710256;5947.494462970696;6030.849228769228;6130.846728831815;6168.537359698041;6230.688465576703;1021.8875923648393;1030.2736570717625;1030.922680798943;1032.26193271811;1047.8323468245196;4000.5599384067727;4120.134771697977;4171.311733563989;4174.624283814472;4219.029623186684;9559.426434413957;11131.942465465769;11250;11422.428878556038;11504.827427588592;11348.09215262777;11369.09047276216;11375.203735738585;11429.942850285695;11459.656210313704;6903.240643529308;8460.534670593053;8462.746117616605;8600.311975041981;8660.653573856975;6184.8763024738355;6196.845078872884;6218.440340369389;6240.282367527792;6295.905561416674;10136.9863013701;10314.742131446774;10567.682969510926;10576.576936922436;10583.894161058292;9353.345265831327;10297.330673506198;10587.417692027013;10626.36241825493;10819.296745711503;7053.964730176316;7562.470627056054;7754.689812407666;7757.961210193913;7864.5281283123195;8562.315014798925;8677.956610217161;8723.694670686495;8769.780755481164;8821.294296456397;6929.411000065051;7419.4806363554035;7812.101608315116;7812.57030863308;7884.132745398117;7552.584607846401;7562.395008399425;7608.619569021637;7680.193579674054;7685.38516918634;7264.709411623688;7278.745243916437;7351.301626345488;7375.336219740249;7405.62971851416;4922.187839006524;5052.317937078595;5140.280360749423;5144.382674079135;5174.560162386088;4883.708246773451;5113.335266415343;5183.92224116631;5189.506996835295;5304.9204261936875 06:25:45 INFO - PROCESS | 1668 | |4;prototype.html;276.8781736036145;312.20232306456995;314.5596165368484;317.06623992342554;323.445291325377;5208.531232189107;5555.833325000246;5565.914646643906;5572.888542229153;5581.386047534768;4488.371627972078;4972.801087956477;5452.32835910403;5502.174673798932;5525.254090697755;3107.6892310768912;3313.6520665330154;3987.940180897289;4155.937660935088;4155.97922010391;3520.7535472516934;3674.3177480439294;4017.939730904024;4099.200655872091;4164.646005089571;4130.814113364906;4136.648384887289;4141.668666506672;4165.146145040259;4188.183304255671;3512.436965550331;3949.6840252779707;4009.1334695692576;4147.963009247688;4172.603602657743;3017.4870272053586;3021.6525099613605;3598.5350035225956;3610.0325322698927;3625.29306785177;4650.883727906829;5303.231031500411;5303.761330740126;5327.360716713981;5337.985782701313;4675.2052151134385;4787.736674482902;4819.554133759893;4830.7101573905675;4853.199222128344;5242.981452140915;5523.696196709179;5788.363280039192;5797.8550536236935;5826.592138550303;3309.619393769699;4005.8998525036814;4008.8574756194344;4024.9597504024878;4032.5765794591734;5577.498025177713;6098.26820781509;6149.969250153766;6160.997675755352;6161.137440758297;3587.9641203588153;3591.174029973121;3686.9353162229236;3853.3064048470983;4039;4100.835966561363;4122.855700050483;4132.152908653718;4153.54311025787;4160.126373461607;4020.7788571628257;4109.445224894661;4486.439195100613;4837.467878533358;4843.903121937612;4855.732934688555;5123.898361852145;5792.507636850831;5815.273090863642;5830.76676932918;1355.8034085057611;1386.5632325817335;1411.3578321863733;1412.3009110490373;1420.7584710599224;839.6515446089926;878.8505953987709;890.4728390809663;897.2687259883174;901.6708901250996;5336.893262134736;5481.314835645544;5507.669539827623;5530.640508366943;5535.501804837584;4972;5339.492748188916;5541.9168712469345;5894.11388355722;6067.939320606825;4783.904321913542;5309.628326017141;5471.863203419946;5515.034868898007;5543.722813859372;2589.6503971963543;3119.4696901526795;3145.685431456836;3159.9144564587264;3184.4268031754045;5839.0949402842625;6035.7283922223605;6253.6560489172625;6265.154204182377;6269.937300626935;4851.442084160367;5288.629795914249;5791.305043394736;5956.55325850563;6321.715522801485;5161.354830646169;5199.66202196856;5432.44675002748;6408.166938297992;6434.227892652912;5350.545203657733;5853.281317384813;6132.516330885998;6140.600860944024;6179.752809887555;5346.599005074557;5359.222912677562;6003.879922401615;6013.173808310087;6025.282794403574;5319.601029922848;5564.387917329172;5880.765578931485;6410;6444.4844412445955;3669.8348574313686;3950.802459877052;3975.562688104364;4016.779077150785;4022.537408198095;5324.9733751331;6045.002574575322;6082.574219804571;6084.8783024340155;6114.331401159269;5475.67145971243;5831.7375718091935;5890.2342695449315;5906.793262235801;5906.907222163742;4757.452892917358;4967.857392799604;5876.206712093813;5890.587658863838;5949.791757288474;5352.437994010573;5418.349798024262;5474.452554744494;5861.3845546217035;5874.970625146847;5466.535344495763;5638.182463542846;5745.9425405745405;5783;5904.3800400959;5013.473585273494;5747.683877386784;5797.594168408171;5837.532997360286;5849.64902105875 06:25:45 INFO - PROCESS | 1668 | |5;yui.html;653.5229282623685;724.7246046502328;727.7962170592233;744.8919906613545;765.8085478630346;14744.705105897876;14800.555983320492;15132;15491.690166196684;15511.922440387796;14283.571492855204;14599.343029563668;14614.707705845876;14638.926805365958;14649.707005859904;733.2740586819037;921.7188757428981;923.8752768376283;944.7826999790052;948.4830767231841;927.1146055516965;946.7017889364828;948.691675205561;956.4309236004601;956.9330146889721;951.6050838901847;1007.6322142418002;1008.425197637347;1010.5200029985772;1011.0344620887035;971.5773638467253;972.6304004478253;994.5872462927877;995.4723996281982;999.9250805384241;708.9397401220903;713.6859781696037;728.1880703016152;743.3792783026125;746.302207436049;15086.773698394423;15108.924455377653;15261.465848695243;15267.312970916224;15316.157611331373;12708.555200567936;12796.61610151697;12800.61598152057;12876;13121.926183559408;9964.701058968243;10125;10127.189824813997;10398.79202415955;10519.63181288659;2886.715411641799;3439.8280085995852;3487.89536313911;3488.8604455821737;3516.1033936346325;59329;88609.11390886137;92421;92559.07440925571;92704.53647731786;9085.545722713838;9394.154526092685;9549.570269337897;9728.562214700356;9765.200123736397;19820;22466.438339041655;23250.418739531648;23331.533369332858;23340.883295583753;7391.260873912674;7427.108746950293;7583.5070720403;7631.732889348847;7717.614119294013;3955.5253369595835;3972.6424621784176;3980.522337319482;3980.920381592352;3987.202559488114;3562.8574857005947;3660.8718694846084;3666.926661466809;3678.5217921670546;3682.502862113634;617.7806878558047;625.371501640848;629.6473974574253;634.3592971099223;634.9492040636738;537.1539824775994;585.2538014032058;605.9939400606025;611.6360765344613;622.0451606783565;7900.7584008633685;8151.225633564802;8366;8394.916050839414;8424.747257582283;521.8356217791384;541.2125357604676;574.8359571867003;581.7207740284687;602.1690067706537;3956.66368358687;3979.7015223857748;4022.3765316376007;4030.556638769733;4058;101262;101665;101777.98222017832;102075.48962255141;102879;82592.52222433337;85193;91953;93259.53370233106;93886;75037.62481187559;75084.62457687677;75253;75882.58528366237;77382.6130869342;7416.295451932057;7636.694532218649;7701.768946931601;7754.457187996786;7950.522968621901;45675.771621141685;46511.30233046575;46577.76711116423;46644;47444.57666270017;64520.032199517984;65303;66055;66062.66968665126;67964.64070718459;9790.608375664893;9838.458884761407;9868.50657467138;10131.645392411525;10261.333013354108;9958.850617240892;10718.946405267923;10805.405702686428;10860.945695271474;11816.468258928197;11890.81091890804;11924.224925379822;12035.277883327028;12182.086343524448;12252.571160009356;11400.543978240778;11711.414429278673;11863.228890122115;11863.60206106237;12022;11460.083193344679;11463.770724585294;11630.53477860876;11739.250985284913;11904.523819047141;9293.488858112598;9590.887612226063;9787.227682819306;9832.508374581384;9942.950285248528;9009.774755630899;9403.905960940303;9560.28297877648;9630.903690963001;9745.220382369534 06:25:45 INFO - PROCESS | 1668 | __end_tp_report 06:25:45 INFO - PROCESS | 1668 | __start_cc_report 06:25:45 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,417 06:25:45 INFO - PROCESS | 1668 | __end_cc_report 06:25:45 INFO - PROCESS | 1668 | __startTimestamp1463145945070__endTimestamp 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | ------- Summary: start ------- 06:25:45 INFO - PROCESS | 1668 | Number of tests: 6 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5786.08 Median:6101.54 stddev:778.82 (12.8%) stddev-sans-first:759.77 06:25:45 INFO - PROCESS | 1668 | Values: 3472.5 3511.1 3511.8 3528.7 3530.3 4975.8 5833.5 5930.8 5939.7 5981.9 5746.7 5868.1 5886.5 5982.9 6039.5 5273.4 5276.3 5288.6 5344.4 5389.3 175.5 5216.9 5259.7 5313.3 5337.4 5258.9 5265.8 5272.8 5289.1 5320.3 5289.7 5316.3 5318.1 5331.8 5341.9 5169.2 5249.8 5298.3 5298.4 5325.6 5697.8 5811.8 5823.9 5847.9 5888.2 5123.9 6155.4 6256.6 6285.7 6300.4 6195.6 6243.9 6267.9 6299.8 6308.5 5965.3 5997.9 5999.7 6016.8 6041.4 6206.8 6225.0 6291.9 6316.2 6351.2 6186.2 6220.2 6227.1 6236.2 6256.6 6228.5 6277.5 6313.7 6319.5 6333.6 5228.9 6122.7 6241.8 6254.7 6288.1 6249.4 6257.2 6263.3 6268.7 6275.9 4997.3 4999.9 5010.6 5013.1 5020.1 4407.4 4455.6 4460.8 4527.9 4541.5 4927.0 5048.3 5817.2 5841.0 6079.5 6281.7 6306.4 6310.6 6322.1 6348.7 5883.1 6014.2 6029.0 6059.2 6082.8 5504.9 5514.3 5519.1 5532.6 5538.0 6480.3 6500.0 6518.9 6534.7 6609.6 5181.0 6221.3 6231.3 6240.4 6319.5 6447.6 6453.0 6492.5 6498.7 6533.4 6133.4 6264.7 6273.2 6280.3 6284.6 6141.4 6168.4 6206.9 6298.9 6317.8 6101.5 6162.4 6176.2 6195.1 6197.8 6057.0 6107.5 6118.3 6174.2 6190.9 5983.9 6106.6 6183.4 6189.6 6195.2 6167.7 6200.0 6201.6 6214.0 6223.7 6012.6 6098.0 6115.1 6185.9 6188.4 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:78420.28 Median:20990.24 stddev:171950.88 (819.2%) stddev-sans-first:172333.73 06:25:45 INFO - PROCESS | 1668 | Values: 501.8 522.5 552.6 565.6 592.2 34584.0 39696.4 41464.8 41532.6 41765.8 42483.4 43190.6 43283.6 43409.8 43459.3 8531.7 9479.9 11843.9 12081.8 12096.1 11768.9 11904.7 11931.9 11962.4 12048.2 12014.2 12060.9 12072.5 12073.8 12151.1 11774.0 11928.2 11971.3 12020.3 12021.8 8732.3 11702.7 11914.2 11933.6 12022.0 42559.4 43308.4 43333.8 44979.8 45084.0 3205.0 3322.3 3524.5 3659.2 4107.0 29457.0 30925.5 31698.7 31744.8 31855.5 2659.8 2731.1 2859.3 2871.3 2874.2 555127.0 559525.8 561916.0 565527.2 565992.0 72939.0 73229.6 73767.0 73953.3 74257.0 76264.0 76706.0 77270.0 77273.5 77358.0 13643.8 13911.4 17381.5 17802.0 17883.4 11632.6 13439.0 13450.3 13454.9 13463.5 18110.9 20915.8 21809.1 21843.0 21849.0 8526.0 9093.0 9110.7 9125.2 9177.5 957.5 1079.5 1128.9 1144.5 1160.5 30722.5 30858.7 31031.5 31148.6 31192.4 1037.8 1073.5 1080.6 1085.7 1110.1 15846.4 15907.3 15942.9 16008.0 16302.8 722748.0 724144.0 724355.0 735319.0 736189.0 64434.7 64760.0 65020.0 65026.0 65166.7 1436.1 1468.3 1471.4 1477.6 1497.7 25908.4 26945.6 27373.0 29307.0 30115.4 56678.4 56737.1 57167.4 57296.4 57535.0 622927.0 626859.0 627801.0 629598.0 630788.0 43924.8 43930.7 44377.1 44386.4 45225.5 48199.8 48236.8 48515.3 49371.0 49977.0 39962.0 39982.6 40212.6 40383.2 41246.4 38321.2 39026.6 39416.6 39448.6 40399.6 38489.4 39405.8 39497.4 40072.4 42035.2 32132.5 34187.0 34791.0 34982.1 34985.0 21979.5 25796.2 25968.2 26066.2 26212.0 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35289.56 Median:11726.11 stddev:99962.74 (852.5%) stddev-sans-first:100208.77 06:25:45 INFO - PROCESS | 1668 | Values: 368.4 394.7 395.2 402.3 412.8 14909.5 18826.5 20250.0 21383.9 21410.0 19688.2 21333.6 21374.5 22454.8 22486.6 5153.8 5511.4 6005.5 6029.4 6031.7 6024.2 6071.8 6283.3 6841.0 6887.4 5839.7 6800.6 6802.5 6806.4 6835.6 5319.7 6103.4 6121.3 6144.9 6168.6 5058.0 5078.4 5760.6 6015.8 6037.4 13268.6 13742.2 13763.2 13930.2 13946.4 1933.9 2118.7 2325.9 2332.5 2397.1 10044.5 10447.2 10462.4 12905.8 14915.8 1541.6 1543.8 1787.5 1796.2 1865.3 99455.5 132157.3 135062.0 136481.0 141255.3 9651.7 11384.9 11624.0 11703.1 11948.2 14847.3 14847.6 14866.6 15198.0 15263.2 8629.8 8809.8 8847.8 8921.2 8930.6 8727.7 8733.6 8741.7 8775.6 8853.5 4642.7 4687.3 4750.8 4774.2 4809.5 1703.4 1739.7 1978.2 2030.9 2033.2 911.4 936.0 945.2 1012.3 1060.8 15225.8 20072.6 20414.9 20741.3 20938.2 960.9 968.8 969.8 991.4 1040.6 8343.2 10768.2 10963.8 10994.2 11109.2 585580.0 586790.0 599362.0 602171.0 602593.0 134036.0 135048.3 136171.0 136790.0 143717.0 1000.7 1012.8 1026.5 1028.6 1036.3 18379.8 18585.3 18625.7 18785.4 18892.4 49834.5 51003.5 51727.0 51884.7 52911.5 76921.0 77551.0 79849.0 80257.0 80309.0 8227.2 8262.6 8330.4 8442.9 8680.4 12318.3 12965.2 13025.3 13200.3 13506.0 10765.8 11094.7 11202.4 11216.3 11439.0 10955.0 11227.8 11467.4 11579.0 11768.7 11310.5 11672.5 11805.6 11848.8 11933.2 8356.5 8460.2 8540.4 8542.4 8697.9 11035.3 11418.4 11474.6 11551.1 11790.9 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5907.21 Median:4887.57 stddev:3336.03 (68.3%) stddev-sans-first:3328.39 06:25:45 INFO - PROCESS | 1668 | Values: 1425.9 1550.7 1755.3 1780.0 1838.1 6617.9 6972.5 8805.9 9232.2 9271.6 7587.8 8938.0 9113.9 9163.9 9168.2 2436.3 2451.5 2475.3 2476.7 2486.0 2104.2 2437.2 2456.4 2494.9 2496.2 1974.8 2351.1 2358.2 2373.7 2382.8 2418.7 2421.0 2442.2 2451.1 2456.1 2361.7 2367.4 2383.1 2384.4 2416.8 9061.6 9219.5 9301.4 9308.7 9310.4 5468.3 5483.5 5535.3 5655.4 5657.9 3595.0 3671.8 3709.3 3715.1 3722.4 1048.6 1061.0 1068.5 1072.4 1086.8 12228.0 12250.6 12346.7 12411.4 12437.2 4891.4 4907.4 5028.8 5053.7 5061.4 10490.7 10514.5 10537.9 10563.9 10598.4 5462.1 5474.3 5517.3 5537.7 5602.9 4478.6 4879.9 4964.3 5012.9 5089.1 3802.7 4086.7 4097.7 4181.7 4247.9 1198.2 1341.7 1362.8 1366.4 1379.7 866.3 876.3 878.6 904.1 957.1 5947.5 6030.8 6130.8 6168.5 6230.7 1021.9 1030.3 1030.9 1032.3 1047.8 4000.6 4120.1 4171.3 4174.6 4219.0 9559.4 11131.9 11250.0 11422.4 11504.8 11348.1 11369.1 11375.2 11429.9 11459.7 6903.2 8460.5 8462.7 8600.3 8660.7 6184.9 6196.8 6218.4 6240.3 6295.9 10137.0 10314.7 10567.7 10576.6 10583.9 9353.3 10297.3 10587.4 10626.4 10819.3 7054.0 7562.5 7754.7 7758.0 7864.5 8562.3 8678.0 8723.7 8769.8 8821.3 6929.4 7419.5 7812.1 7812.6 7884.1 7552.6 7562.4 7608.6 7680.2 7685.4 7264.7 7278.7 7351.3 7375.3 7405.6 4922.2 5052.3 5140.3 5144.4 5174.6 4883.7 5113.3 5183.9 5189.5 5304.9 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4676.68 Median:5326.17 stddev:1472.16 (27.6%) stddev-sans-first:1438.78 06:25:45 INFO - PROCESS | 1668 | Values: 276.9 312.2 314.6 317.1 323.4 5208.5 5555.8 5565.9 5572.9 5581.4 4488.4 4972.8 5452.3 5502.2 5525.3 3107.7 3313.7 3987.9 4155.9 4156.0 3520.8 3674.3 4017.9 4099.2 4164.6 4130.8 4136.6 4141.7 4165.1 4188.2 3512.4 3949.7 4009.1 4148.0 4172.6 3017.5 3021.7 3598.5 3610.0 3625.3 4650.9 5303.2 5303.8 5327.4 5338.0 4675.2 4787.7 4819.6 4830.7 4853.2 5243.0 5523.7 5788.4 5797.9 5826.6 3309.6 4005.9 4008.9 4025.0 4032.6 5577.5 6098.3 6150.0 6161.0 6161.1 3588.0 3591.2 3686.9 3853.3 4039.0 4100.8 4122.9 4132.2 4153.5 4160.1 4020.8 4109.4 4486.4 4837.5 4843.9 4855.7 5123.9 5792.5 5815.3 5830.8 1355.8 1386.6 1411.4 1412.3 1420.8 839.7 878.9 890.5 897.3 901.7 5336.9 5481.3 5507.7 5530.6 5535.5 4972.0 5339.5 5541.9 5894.1 6067.9 4783.9 5309.6 5471.9 5515.0 5543.7 2589.7 3119.5 3145.7 3159.9 3184.4 5839.1 6035.7 6253.7 6265.2 6269.9 4851.4 5288.6 5791.3 5956.6 6321.7 5161.4 5199.7 5432.4 6408.2 6434.2 5350.5 5853.3 6132.5 6140.6 6179.8 5346.6 5359.2 6003.9 6013.2 6025.3 5319.6 5564.4 5880.8 6410.0 6444.5 3669.8 3950.8 3975.6 4016.8 4022.5 5325.0 6045.0 6082.6 6084.9 6114.3 5475.7 5831.7 5890.2 5906.8 5906.9 4757.5 4967.9 5876.2 5890.6 5949.8 5352.4 5418.3 5474.5 5861.4 5875.0 5466.5 5638.2 5745.9 5783.0 5904.4 5013.5 5747.7 5797.6 5837.5 5849.6 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:19045.53 Median:29965.58 stddev:27776.66 (92.7%) stddev-sans-first:27820.25 06:25:45 INFO - PROCESS | 1668 | Values: 653.5 724.7 727.8 744.9 765.8 14744.7 14800.6 15132.0 15491.7 15511.9 14283.6 14599.3 14614.7 14638.9 14649.7 733.3 921.7 923.9 944.8 948.5 927.1 946.7 948.7 956.4 956.9 951.6 1007.6 1008.4 1010.5 1011.0 971.6 972.6 994.6 995.5 999.9 708.9 713.7 728.2 743.4 746.3 15086.8 15108.9 15261.5 15267.3 15316.2 12708.6 12796.6 12800.6 12876.0 13121.9 9964.7 10125.0 10127.2 10398.8 10519.6 2886.7 3439.8 3487.9 3488.9 3516.1 59329.0 88609.1 92421.0 92559.1 92704.5 9085.5 9394.2 9549.6 9728.6 9765.2 19820.0 22466.4 23250.4 23331.5 23340.9 7391.3 7427.1 7583.5 7631.7 7717.6 3955.5 3972.6 3980.5 3980.9 3987.2 3562.9 3660.9 3666.9 3678.5 3682.5 617.8 625.4 629.6 634.4 634.9 537.2 585.3 606.0 611.6 622.0 7900.8 8151.2 8366.0 8394.9 8424.7 521.8 541.2 574.8 581.7 602.2 3956.7 3979.7 4022.4 4030.6 4058.0 101262.0 101665.0 101778.0 102075.5 102879.0 82592.5 85193.0 91953.0 93259.5 93886.0 75037.6 75084.6 75253.0 75882.6 77382.6 7416.3 7636.7 7701.8 7754.5 7950.5 45675.8 46511.3 46577.8 46644.0 47444.6 64520.0 65303.0 66055.0 66062.7 67964.6 9790.6 9838.5 9868.5 10131.6 10261.3 9958.9 10718.9 10805.4 10860.9 11816.5 11890.8 11924.2 12035.3 12182.1 12252.6 11400.5 11711.4 11863.2 11863.6 12022.0 11460.1 11463.8 11630.5 11739.3 11904.5 9293.5 9590.9 9787.2 9832.5 9943.0 9009.8 9403.9 9560.3 9630.9 9745.2 06:25:45 INFO - PROCESS | 1668 | -------- Summary: end -------- 06:25:45 INFO - PROCESS | 1668 | 06:25:45 INFO - TEST-INFO | 1668: exit 0 06:25:45 INFO - TEST-OK | dromaeo_css | took 1111689ms 06:25:45 INFO - TEST-START | kraken 06:25:45 INFO - operating with platform_type : mac_ 06:25:45 INFO - Initialising browser for kraken test... 06:25:45 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpeUV_Fa/profile http://localhost:49275/getInfo.html) 06:25:48 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 06:25:48 INFO - PROCESS | 1677 | colorDepth:24 06:25:48 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 06:25:48 INFO - PROCESS | 1677 | __metrics 06:25:48 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 06:25:49 INFO - TEST-INFO | 1677: exit 0 06:25:49 INFO - Browser initialized. 06:25:49 INFO - Running cycle 1/1 for kraken test... 06:25:49 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/tmpeUV_Fa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:26:01 INFO - PROCESS | 1680 | RSS: Main: 249872384 06:26:01 INFO - PROCESS | 1680 | 06:26:04 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:26:05 INFO - PROCESS | 1680 | RSS: Main: 261799936 06:26:05 INFO - PROCESS | 1680 | 06:26:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:26:08 INFO - PROCESS | 1680 | RSS: Main: 266600448 06:26:08 INFO - PROCESS | 1680 | 06:26:12 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:26:12 INFO - PROCESS | 1680 | RSS: Main: 266969088 06:26:12 INFO - PROCESS | 1680 | 06:26:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:26:15 INFO - PROCESS | 1680 | RSS: Main: 267554816 06:26:15 INFO - PROCESS | 1680 | 06:26:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:26:18 INFO - PROCESS | 1680 | RSS: Main: 268066816 06:26:18 INFO - PROCESS | 1680 | 06:26:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:26:22 INFO - PROCESS | 1680 | RSS: Main: 268484608 06:26:22 INFO - PROCESS | 1680 | 06:26:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:26:25 INFO - PROCESS | 1680 | RSS: Main: 270127104 06:26:25 INFO - PROCESS | 1680 | 06:26:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:26:29 INFO - PROCESS | 1680 | RSS: Main: 270716928 06:26:29 INFO - PROCESS | 1680 | 06:26:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:26:31 INFO - PROCESS | 1680 | RSS: Main: 269848576 06:26:31 INFO - PROCESS | 1680 | 06:26:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:26:34 INFO - PROCESS | 1680 | RSS: Main: 269889536 06:26:34 INFO - PROCESS | 1680 | 06:26:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:26:37 INFO - PROCESS | 1680 | RSS: Main: 270229504 06:26:37 INFO - PROCESS | 1680 | 06:26:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:26:41 INFO - PROCESS | 1680 | RSS: Main: 270454784 06:26:41 INFO - PROCESS | 1680 | 06:26:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:26:44 INFO - PROCESS | 1680 | RSS: Main: 270405632 06:26:44 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:26:46 INFO - PROCESS | 1680 | RSS: Main: 272920576 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | __start_tp_report 06:26:46 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 06:26:46 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 06:26:46 INFO - PROCESS | 1680 | |i|pagename|runs| 06:26:46 INFO - PROCESS | 1680 | |0;ai-astar;114;115;122;169;117;115;110;117;152;167 06:26:46 INFO - PROCESS | 1680 | |1;audio-beat-detection;156;150;172;268;148;154;128;145;200;145 06:26:46 INFO - PROCESS | 1680 | |2;audio-dft;204;229;238;202;195;200;196;195;195;193 06:26:46 INFO - PROCESS | 1680 | |3;audio-fft;64;81;75;167;55;57;60;70;130;57 06:26:46 INFO - PROCESS | 1680 | |4;audio-oscillator;77;89;147;77;80;80;121;80;80;122 06:26:46 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;114;102;135;87;90;87;110;130;103;131 06:26:46 INFO - PROCESS | 1680 | |6;imaging-darkroom;152;106;104;96;96;94;95;96;96;94 06:26:46 INFO - PROCESS | 1680 | |7;imaging-desaturate;90;92;104;146;106;106;91;89;91;90 06:26:46 INFO - PROCESS | 1680 | |8;json-parse-financial;59;60;58;59;59;60;57;121;61;59 06:26:46 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;68;66;67;78;67;81;99;99;92;69 06:26:46 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;87;85;88;88;87;109;87;105;109;77 06:26:46 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;140;132;140;146;141;134;137;157;247;146 06:26:46 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;163;167;165;164;164;194;162;228;201;164 06:26:46 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;64;66;80;87;85;80;85;84;64;58 06:26:46 INFO - PROCESS | 1680 | __end_tp_report 06:26:46 INFO - PROCESS | 1680 | __start_cc_report 06:26:46 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1074 06:26:46 INFO - PROCESS | 1680 | __end_cc_report 06:26:46 INFO - PROCESS | 1680 | __startTimestamp1463146006853__endTimestamp 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | ------- Summary: start ------- 06:26:46 INFO - PROCESS | 1680 | Number of tests: 14 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:129.80 Median:119.50 stddev:23.29 (19.5%) stddev-sans-first:23.99 06:26:46 INFO - PROCESS | 1680 | Values: 114.0 115.0 122.0 169.0 117.0 115.0 110.0 117.0 152.0 167.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:166.60 Median:155.00 stddev:40.47 (26.1%) stddev-sans-first:42.75 06:26:46 INFO - PROCESS | 1680 | Values: 156.0 150.0 172.0 268.0 148.0 154.0 128.0 145.0 200.0 145.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:204.70 Median:201.00 stddev:15.72 (7.8%) stddev-sans-first:16.67 06:26:46 INFO - PROCESS | 1680 | Values: 204.0 229.0 238.0 202.0 195.0 200.0 196.0 195.0 195.0 193.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:81.60 Median:62.00 stddev:37.28 (60.1%) stddev-sans-first:38.99 06:26:46 INFO - PROCESS | 1680 | Values: 64.0 81.0 75.0 167.0 55.0 57.0 60.0 70.0 130.0 57.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:80.00 stddev:25.15 (31.4%) stddev-sans-first:25.79 06:26:46 INFO - PROCESS | 1680 | Values: 77.0 89.0 147.0 77.0 80.0 80.0 121.0 80.0 80.0 122.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:133.00 stddev:18.38 (13.8%) stddev-sans-first:19.40 06:26:46 INFO - PROCESS | 1680 | Values: 114.0 102.0 135.0 87.0 90.0 87.0 110.0 130.0 103.0 131.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:102.90 Median:95.50 stddev:17.74 (18.6%) stddev-sans-first:4.39 06:26:46 INFO - PROCESS | 1680 | Values: 152.0 106.0 104.0 96.0 96.0 94.0 95.0 96.0 96.0 94.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:90.00 stddev:17.48 (19.4%) stddev-sans-first:18.12 06:26:46 INFO - PROCESS | 1680 | Values: 90.0 92.0 104.0 146.0 106.0 106.0 91.0 89.0 91.0 90.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:59.00 stddev:19.60 (33.2%) stddev-sans-first:20.66 06:26:46 INFO - PROCESS | 1680 | Values: 59.0 60.0 58.0 59.0 59.0 60.0 57.0 121.0 61.0 59.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:78.60 Median:79.50 stddev:13.54 (17.0%) stddev-sans-first:13.81 06:26:46 INFO - PROCESS | 1680 | Values: 68.0 66.0 67.0 78.0 67.0 81.0 99.0 99.0 92.0 69.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:87.00 stddev:11.19 (12.9%) stddev-sans-first:11.71 06:26:46 INFO - PROCESS | 1680 | Values: 87.0 85.0 88.0 88.0 87.0 109.0 87.0 105.0 109.0 77.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:143.50 stddev:34.12 (23.8%) stddev-sans-first:35.92 06:26:46 INFO - PROCESS | 1680 | Values: 140.0 132.0 140.0 146.0 141.0 134.0 137.0 157.0 247.0 146.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:166.00 stddev:22.70 (13.7%) stddev-sans-first:23.49 06:26:46 INFO - PROCESS | 1680 | Values: 163.0 167.0 165.0 164.0 164.0 194.0 162.0 228.0 201.0 164.0 06:26:46 INFO - PROCESS | 1680 | 06:26:46 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:82.00 stddev:10.99 (13.4%) stddev-sans-first:10.86 06:26:46 INFO - PROCESS | 1680 | Values: 64.0 66.0 80.0 87.0 85.0 80.0 85.0 84.0 64.0 58.0 06:26:46 INFO - PROCESS | 1680 | -------- Summary: end -------- 06:26:46 INFO - PROCESS | 1680 | 06:26:47 INFO - TEST-INFO | 1680: exit 0 06:26:47 INFO - TEST-OK | kraken | took 62185ms 06:26:47 INFO - SUITE-END | took 1173s 06:26:48 INFO - Completed test suite (00:19:34) 06:26:48 INFO - javascript benchmark 06:26:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7427.475451589292, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3472.4964880092402, 3511.069566564861, 3511.806850623215, 3528.682418582328, 3530.329237444887, 4975.751212439377, 5833.508198687008, 5930.792422265216, 5939.703014849251, 5981.910271345933, 5746.655200687962, 5868.119782032697, 5886.499647529965, 5982.8803423931495, 6039.547033972448, 5273.446288139738, 5276.34045744282, 5288.603354748409, 5344.385395679488, 5389.299391079174, 175.4933219671444, 5216.921746173811, 5259.658122222062, 5313.335833020872, 5337.412884582693, 5258.8685282867855, 5265.842024739264, 5272.8154514592, 5289.074411977907, 5320.308359913187, 5289.68261904287, 5316.282301889234, 5318.095923692981, 5331.813386531453, 5341.946580534183, 5169.198774190007, 5249.790008399659, 5298.284731561267, 5298.417174110883, 5325.627206095575, 5697.829065128053, 5811.82564523065, 5823.941760582382, 5847.8538036549, 5888.234529511179, 5123.923141152848, 6155.353687862799, 6256.593321434138, 6285.685715714196, 6300.369963003709, 6195.5663103582765, 6243.937560624381, 6267.8746425071695, 6299.779507717208, 6308.463780578656, 5965.3139888912465, 5997.909095434867, 5999.730012149464, 6016.819495415145, 6041.426064523863, 6206.751729930752, 6224.968875155595, 6291.874162516724, 6316.178896743394, 6351.2060992375955, 6186.226721659793, 6220.191375121205, 6227.1282020517165, 6236.18929539166, 6256.562040657111, 6228.532860035515, 6277.466415354746, 6313.652749098844, 6319.494440444753, 6333.588316759396, 5228.869278268074, 6122.693865306717, 6241.812745617548, 6254.655993920287, 6288.119663247149, 6249.437550620466, 6257.155284036597, 6263.31103578606, 6268.655223962634, 6275.937240627627, 4997.275395067768, 4999.900001999939, 5010.599152067826, 5013.147764879979, 5020.096382651158, 4407.4050003249795, 4455.554444555583, 4460.77696115193, 4527.932081018787, 4541.500434952219, 4926.950730492721, 5048.3184770056305, 5817.214676018768, 5841.036229022248, 6079.544034197453, 6281.685915704196, 6306.432421082125, 6310.5898116622275, 6322.051692246107, 6348.746050158034, 5883.14694369314, 6014.1580178775, 6029.0, 6059.242594675666, 6082.847928801815, 5504.91742623861, 5514.338279406497, 5519.116941289374, 5532.585056120807, 5538.0, 6480.31956644555, 6500.0, 6518.869622607522, 6534.673266336759, 6609.603423794588, 5180.9740951295, 6221.3156552780065, 6231.2834024086, 6240.4071613196675, 6319.52603554726, 6447.6453795041725, 6452.9677351612945, 6492.480601551959, 6498.740050397932, 6533.444657204191, 6133.44798968095, 6264.74941002373, 6273.153124328157, 6280.27776805655, 6284.560080794484, 6141.424638644238, 6168.444839964424, 6206.875862482635, 6298.905516417166, 6317.778877739441, 6101.542384321282, 6162.352952939877, 6176.227971503562, 6195.132681424514, 6197.783077592263, 6057.030875059969, 6107.480864126599, 6118.326984031664, 6174.197354343906, 6190.907136392868, 5983.931942362131, 6106.582013105306, 6183.412575805291, 6189.6286222826775, 6195.2255968004, 6167.722452489559, 6200.0, 6201.627902325875, 6214.0, 6223.688815559294, 6012.579119461596, 6098.0, 6115.1438798569125, 6185.907211391743, 6188.412100850412], "value": 5740.628181954429, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.81432869838164, 522.5140619224122, 552.6242155334373, 565.5927732032936, 592.2034863109117, 34584.0, 39696.40455393168, 41464.79267603662, 41532.58467415325, 41765.79117104418, 42483.36274955878, 43190.568094319045, 43283.56716432835, 43409.78295108528, 43459.34810977838, 8531.658733650678, 9479.85780213299, 11843.940780296087, 12081.818772718416, 12096.092793040558, 11768.882311176907, 11904.702382440422, 11931.880681193206, 11962.401879906014, 12048.156629035971, 12014.219075760091, 12060.939695301555, 12072.456739446747, 12073.758524829498, 12151.149419540636, 11774.0, 11928.224665396721, 11971.2817230966, 12020.278783273032, 12021.819672704916, 8732.301415886714, 11702.707432314175, 11914.225575337576, 11933.641990740292, 12022.0, 42559.361609575884, 43308.350374744405, 43333.78333108314, 44979.775101124615, 45084.0, 3205.0, 3322.3355328934067, 3524.4713293006, 3659.249853779969, 4107.0, 29457.0, 30925.536116958265, 31698.683013169804, 31744.841275793475, 31855.52216716751, 2659.7606215440705, 2731.112388473754, 2859.3280579063903, 2871.267826704198, 2874.238326843388, 555127.0, 559525.8213380408, 561916.0, 565527.1723641397, 565992.0, 72939.0, 73229.6338518304, 73767.0, 73953.26046739571, 74257.0, 76264.0, 76706.0, 77270.0, 77273.45453090906, 77358.0, 13643.795343069862, 13911.373988170355, 17381.52257058164, 17802.0, 17883.374081907332, 11632.592859250055, 13439.0, 13450.26023568713, 13454.865451345557, 13463.461461541428, 18110.90944545269, 20915.79084209169, 21809.127634894743, 21843.0, 21849.0, 8526.0, 9093.0, 9110.681126160553, 9125.22435592982, 9177.541122943827, 957.5355952363099, 1079.519613771864, 1128.8645362556547, 1144.4849817582121, 1160.4835848047537, 30722.539161912588, 30858.69141308603, 31031.518338214555, 31148.598313075967, 31192.376152476823, 1037.8080055189814, 1073.5276478349501, 1080.621782376162, 1085.696005118568, 1110.0564520157802, 15846.366145354288, 15907.284172212047, 15942.920285398499, 16008.0, 16302.83697163037, 722748.0, 724144.0, 724355.0, 735319.0, 736189.0, 64434.67782661057, 64760.0, 65020.0, 65026.0, 65166.67416662981, 1436.1096120405207, 1468.3172324868988, 1471.4114354258168, 1477.5641185850416, 1497.6779992301708, 25908.35229119287, 26945.59581606238, 27373.0, 29307.0, 30115.397692046474, 56678.43321566732, 56737.14894276507, 57167.428325717876, 57296.42703572911, 57535.0, 622927.0, 626859.0, 627801.0, 629598.0, 630788.0, 43924.780376097915, 43930.68207953766, 44377.11245775131, 44386.44647437324, 45225.54774452213, 48199.75900120477, 48236.75881620569, 48515.27227091526, 49371.0, 49977.0, 39962.0, 39982.60017399789, 40212.59787402205, 40383.192336152526, 41246.38130428106, 38321.23357532778, 39026.60973390343, 39416.60583394244, 39448.605513944494, 40399.59600403958, 38489.422658660704, 39405.80297098611, 39497.42010319555, 40072.39891401573, 42035.15929681328, 32132.518012230303, 34186.974390768315, 34791.0, 34982.12544686403, 34985.0, 21979.461437699207, 25796.226113216635, 25968.22095337143, 26066.220398041798, 26212.0], "value": 21841.654873479863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.4344531144692, 394.686224451561, 395.23324749985017, 402.2959820314449, 412.79154027216254, 14909.478168264113, 18826.49388048956, 20250.0, 21383.893080534595, 21410.0, 19688.212471501123, 21333.57332853342, 21374.465638359048, 22454.775452245474, 22486.55026899461, 5153.845384638448, 5511.366192887809, 6005.459508644221, 6029.397060293979, 6031.6984150792505, 6024.21685180928, 6071.78748743794, 6283.3088360280335, 6841.0, 6887.414569761576, 5839.737211825478, 6800.557963732366, 6802.489813264, 6806.3874251317375, 6835.555688880207, 5319.723266416052, 6103.359147289537, 6121.296050954153, 6144.877102457948, 6168.629882207082, 5058.048071370234, 5078.365204349457, 5760.6255593386295, 6015.819525414245, 6037.426444487811, 13268.601941941757, 13742.244176570284, 13763.174209547358, 13930.164190148522, 13946.442142314296, 1933.9033048347667, 2118.650422680256, 2325.918592849242, 2332.4635333873134, 2397.11306816479, 10044.497775111215, 10447.164226861833, 10462.424566648831, 12905.838474537337, 14915.776263356058, 1541.6223025358747, 1543.8070241219848, 1787.5352408373917, 1796.2276221224868, 1865.2911893480525, 99455.50272248665, 132157.33921330332, 135062.0, 136481.0, 141255.29372353177, 9651.662191823252, 11384.943075284571, 11624.0, 11703.122265830098, 11948.22336548139, 14847.331870065656, 14847.554573362817, 14866.628334291512, 15198.0, 15263.160526171167, 8629.78425539354, 8809.823803523894, 8847.778805529784, 8921.241694455914, 8930.553472326357, 8727.738167854974, 8733.606987685569, 8741.650333986569, 8775.561221938877, 8853.46879187238, 4642.698224615457, 4687.32033855096, 4750.809967601326, 4774.236122220428, 4809.495003024632, 1703.4208369154298, 1739.6607661505884, 1978.2284908885547, 2030.9081645904741, 2033.2172113736317, 911.3620465674055, 935.9906400936039, 945.2438049560325, 1012.3116280929039, 1060.7825395793843, 15225.771613425808, 20072.598548028956, 20414.897925510573, 20741.27405540799, 20938.162473501194, 960.9279304052224, 968.790604822047, 969.7575606098476, 991.4348821171862, 1040.552562398176, 8343.249107580346, 10768.192385571114, 10963.83554246687, 10994.175436842268, 11109.222354435273, 585580.0, 586790.0, 599362.0, 602171.0, 602593.0, 134036.0, 135048.3247583756, 136171.0, 136790.0, 143717.0, 1000.7290740759195, 1012.7670635753818, 1026.486756621704, 1028.6091285311684, 1036.3212096077082, 18379.816201838345, 18585.34951276699, 18625.696201265782, 18785.436436906915, 18892.433227003214, 49834.50165498299, 51003.48996509987, 51727.0, 51884.74057629688, 52911.4708852922, 76921.0, 77551.0, 79849.0, 80257.0, 80309.0, 8227.218414250636, 8262.586870656563, 8330.416870818985, 8442.873356899774, 8680.392372533863, 12318.260904345769, 12965.15726477776, 13025.348732563145, 13200.273984930922, 13505.98705097102, 10765.838512422477, 11094.722631934266, 11202.383868886964, 11216.270942388719, 11439.0, 10955.014048735651, 11227.775444491228, 11467.369294688539, 11579.0, 11768.70578235551, 11310.4910279036, 11672.47473863695, 11805.586804461802, 11848.822267666164, 11933.164678472423, 8356.456830306011, 8460.196281353261, 8540.35947303967, 8542.44474109181, 8697.913020869963, 11035.282706624364, 11418.371989540656, 11474.598389056342, 11551.133664975328, 11790.882091179321], "value": 9614.980736725964, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.921574313413, 1550.720870243356, 1755.3329734700812, 1779.9911000444995, 1838.0625880800796, 6617.86764264715, 6972.511924165297, 8805.867911981326, 9232.169104780569, 9271.629134834599, 7587.81030474237, 8937.955310223457, 9113.863292050624, 9163.908360916388, 9168.174864262215, 2436.281297017384, 2451.460678650694, 2475.306914064056, 2476.7151777545632, 2486.0428734937, 2104.231955336299, 2437.2322718343667, 2456.3981824452944, 2494.8627825469594, 2496.226169887341, 1974.7926467720897, 2351.1183306260154, 2358.2335740884196, 2373.679553260305, 2382.7736365045293, 2418.6734790803193, 2421.031587365068, 2442.206282958037, 2451.1298489036312, 2456.1403508771964, 2361.7047869016374, 2367.3844800351862, 2383.0944241188413, 2384.427737343025, 2416.830821842472, 9061.637534498677, 9219.539023048821, 9301.395409298442, 9308.720738377859, 9310.394824336396, 5468.2891224141, 5483.479069488392, 5535.280413546213, 5655.4061823508355, 5657.9434205658235, 3594.982025089858, 3671.7796932183887, 3709.2581483703366, 3715.1269451678836, 3722.4416337549314, 1048.64346122318, 1061.0185578340004, 1068.4711068021302, 1072.447689439939, 1086.8261078227524, 12228.0, 12250.571230006995, 12346.691332716664, 12411.441485133191, 12437.191582547106, 4891.437484689235, 4907.435644900811, 5028.84913452597, 5053.747312634354, 5061.4179369373, 10490.685279441628, 10514.526846291936, 10537.947310263553, 10563.94718026405, 10598.364098154136, 5462.071447853875, 5474.315710536183, 5517.337919449611, 5537.667739935617, 5602.915956260659, 4478.552144785495, 4879.902401952012, 4964.280179374043, 5012.899742005139, 5089.083964886356, 3802.6767724743154, 4086.6922584772587, 4097.713160078825, 4181.749095054246, 4247.893802654959, 1198.2151690642643, 1341.6578772412972, 1362.7751421015619, 1366.4261010375667, 1379.703363776793, 866.2593482572436, 876.3252295732251, 878.5563290538237, 904.0823564082461, 957.095544710256, 5947.494462970696, 6030.849228769228, 6130.846728831815, 6168.537359698041, 6230.688465576703, 1021.8875923648393, 1030.2736570717625, 1030.922680798943, 1032.26193271811, 1047.8323468245196, 4000.5599384067727, 4120.134771697977, 4171.311733563989, 4174.624283814472, 4219.029623186684, 9559.426434413957, 11131.942465465769, 11250.0, 11422.428878556038, 11504.827427588592, 11348.09215262777, 11369.09047276216, 11375.203735738585, 11429.942850285695, 11459.656210313704, 6903.240643529308, 8460.534670593053, 8462.746117616605, 8600.311975041981, 8660.653573856975, 6184.8763024738355, 6196.845078872884, 6218.440340369389, 6240.282367527792, 6295.905561416674, 10136.9863013701, 10314.742131446774, 10567.682969510926, 10576.576936922436, 10583.894161058292, 9353.345265831327, 10297.330673506198, 10587.417692027013, 10626.36241825493, 10819.296745711503, 7053.964730176316, 7562.470627056054, 7754.689812407666, 7757.961210193913, 7864.5281283123195, 8562.315014798925, 8677.956610217161, 8723.694670686495, 8769.780755481164, 8821.294296456397, 6929.411000065051, 7419.4806363554035, 7812.101608315116, 7812.57030863308, 7884.132745398117, 7552.584607846401, 7562.395008399425, 7608.619569021637, 7680.193579674054, 7685.38516918634, 7264.709411623688, 7278.745243916437, 7351.301626345488, 7375.336219740249, 7405.62971851416, 4922.187839006524, 5052.317937078595, 5140.280360749423, 5144.382674079135, 5174.560162386088, 4883.708246773451, 5113.335266415343, 5183.92224116631, 5189.506996835295, 5304.9204261936875], "value": 4740.841881994268, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.8781736036145, 312.20232306456995, 314.5596165368484, 317.06623992342554, 323.445291325377, 5208.531232189107, 5555.833325000246, 5565.914646643906, 5572.888542229153, 5581.386047534768, 4488.371627972078, 4972.801087956477, 5452.32835910403, 5502.174673798932, 5525.254090697755, 3107.6892310768912, 3313.6520665330154, 3987.940180897289, 4155.937660935088, 4155.97922010391, 3520.7535472516934, 3674.3177480439294, 4017.939730904024, 4099.200655872091, 4164.646005089571, 4130.814113364906, 4136.648384887289, 4141.668666506672, 4165.146145040259, 4188.183304255671, 3512.436965550331, 3949.6840252779707, 4009.1334695692576, 4147.963009247688, 4172.603602657743, 3017.4870272053586, 3021.6525099613605, 3598.5350035225956, 3610.0325322698927, 3625.29306785177, 4650.883727906829, 5303.231031500411, 5303.761330740126, 5327.360716713981, 5337.985782701313, 4675.2052151134385, 4787.736674482902, 4819.554133759893, 4830.7101573905675, 4853.199222128344, 5242.981452140915, 5523.696196709179, 5788.363280039192, 5797.8550536236935, 5826.592138550303, 3309.619393769699, 4005.8998525036814, 4008.8574756194344, 4024.9597504024878, 4032.5765794591734, 5577.498025177713, 6098.26820781509, 6149.969250153766, 6160.997675755352, 6161.137440758297, 3587.9641203588153, 3591.174029973121, 3686.9353162229236, 3853.3064048470983, 4039.0, 4100.835966561363, 4122.855700050483, 4132.152908653718, 4153.54311025787, 4160.126373461607, 4020.7788571628257, 4109.445224894661, 4486.439195100613, 4837.467878533358, 4843.903121937612, 4855.732934688555, 5123.898361852145, 5792.507636850831, 5815.273090863642, 5830.76676932918, 1355.8034085057611, 1386.5632325817335, 1411.3578321863733, 1412.3009110490373, 1420.7584710599224, 839.6515446089926, 878.8505953987709, 890.4728390809663, 897.2687259883174, 901.6708901250996, 5336.893262134736, 5481.314835645544, 5507.669539827623, 5530.640508366943, 5535.501804837584, 4972.0, 5339.492748188916, 5541.9168712469345, 5894.11388355722, 6067.939320606825, 4783.904321913542, 5309.628326017141, 5471.863203419946, 5515.034868898007, 5543.722813859372, 2589.6503971963543, 3119.4696901526795, 3145.685431456836, 3159.9144564587264, 3184.4268031754045, 5839.0949402842625, 6035.7283922223605, 6253.6560489172625, 6265.154204182377, 6269.937300626935, 4851.442084160367, 5288.629795914249, 5791.305043394736, 5956.55325850563, 6321.715522801485, 5161.354830646169, 5199.66202196856, 5432.44675002748, 6408.166938297992, 6434.227892652912, 5350.545203657733, 5853.281317384813, 6132.516330885998, 6140.600860944024, 6179.752809887555, 5346.599005074557, 5359.222912677562, 6003.879922401615, 6013.173808310087, 6025.282794403574, 5319.601029922848, 5564.387917329172, 5880.765578931485, 6410.0, 6444.4844412445955, 3669.8348574313686, 3950.802459877052, 3975.562688104364, 4016.779077150785, 4022.537408198095, 5324.9733751331, 6045.002574575322, 6082.574219804571, 6084.8783024340155, 6114.331401159269, 5475.67145971243, 5831.7375718091935, 5890.2342695449315, 5906.793262235801, 5906.907222163742, 4757.452892917358, 4967.857392799604, 5876.206712093813, 5890.587658863838, 5949.791757288474, 5352.437994010573, 5418.349798024262, 5474.452554744494, 5861.3845546217035, 5874.970625146847, 5466.535344495763, 5638.182463542846, 5745.9425405745405, 5783.0, 5904.3800400959, 5013.473585273494, 5747.683877386784, 5797.594168408171, 5837.532997360286, 5849.64902105875], "value": 4206.43085568581, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.5229282623685, 724.7246046502328, 727.7962170592233, 744.8919906613545, 765.8085478630346, 14744.705105897876, 14800.555983320492, 15132.0, 15491.690166196684, 15511.922440387796, 14283.571492855204, 14599.343029563668, 14614.707705845876, 14638.926805365958, 14649.707005859904, 733.2740586819037, 921.7188757428981, 923.8752768376283, 944.7826999790052, 948.4830767231841, 927.1146055516965, 946.7017889364828, 948.691675205561, 956.4309236004601, 956.9330146889721, 951.6050838901847, 1007.6322142418002, 1008.425197637347, 1010.5200029985772, 1011.0344620887035, 971.5773638467253, 972.6304004478253, 994.5872462927877, 995.4723996281982, 999.9250805384241, 708.9397401220903, 713.6859781696037, 728.1880703016152, 743.3792783026125, 746.302207436049, 15086.773698394423, 15108.924455377653, 15261.465848695243, 15267.312970916224, 15316.157611331373, 12708.555200567936, 12796.61610151697, 12800.61598152057, 12876.0, 13121.926183559408, 9964.701058968243, 10125.0, 10127.189824813997, 10398.79202415955, 10519.63181288659, 2886.715411641799, 3439.8280085995852, 3487.89536313911, 3488.8604455821737, 3516.1033936346325, 59329.0, 88609.11390886137, 92421.0, 92559.07440925571, 92704.53647731786, 9085.545722713838, 9394.154526092685, 9549.570269337897, 9728.562214700356, 9765.200123736397, 19820.0, 22466.438339041655, 23250.418739531648, 23331.533369332858, 23340.883295583753, 7391.260873912674, 7427.108746950293, 7583.5070720403, 7631.732889348847, 7717.614119294013, 3955.5253369595835, 3972.6424621784176, 3980.522337319482, 3980.920381592352, 3987.202559488114, 3562.8574857005947, 3660.8718694846084, 3666.926661466809, 3678.5217921670546, 3682.502862113634, 617.7806878558047, 625.371501640848, 629.6473974574253, 634.3592971099223, 634.9492040636738, 537.1539824775994, 585.2538014032058, 605.9939400606025, 611.6360765344613, 622.0451606783565, 7900.7584008633685, 8151.225633564802, 8366.0, 8394.916050839414, 8424.747257582283, 521.8356217791384, 541.2125357604676, 574.8359571867003, 581.7207740284687, 602.1690067706537, 3956.66368358687, 3979.7015223857748, 4022.3765316376007, 4030.556638769733, 4058.0, 101262.0, 101665.0, 101777.98222017832, 102075.48962255141, 102879.0, 82592.52222433337, 85193.0, 91953.0, 93259.53370233106, 93886.0, 75037.62481187559, 75084.62457687677, 75253.0, 75882.58528366237, 77382.6130869342, 7416.295451932057, 7636.694532218649, 7701.768946931601, 7754.457187996786, 7950.522968621901, 45675.771621141685, 46511.30233046575, 46577.76711116423, 46644.0, 47444.57666270017, 64520.032199517984, 65303.0, 66055.0, 66062.66968665126, 67964.64070718459, 9790.608375664893, 9838.458884761407, 9868.50657467138, 10131.645392411525, 10261.333013354108, 9958.850617240892, 10718.946405267923, 10805.405702686428, 10860.945695271474, 11816.468258928197, 11890.81091890804, 11924.224925379822, 12035.277883327028, 12182.086343524448, 12252.571160009356, 11400.543978240778, 11711.414429278673, 11863.228890122115, 11863.60206106237, 12022.0, 11460.083193344679, 11463.770724585294, 11630.53477860876, 11739.250985284913, 11904.523819047141, 9293.488858112598, 9590.887612226063, 9787.227682819306, 9832.508374581384, 9942.950285248528, 9009.774755630899, 9403.905960940303, 9560.28297877648, 9630.903690963001, 9745.220382369534], "value": 6982.932383259758, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1630.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 122.0, 169.0, 117.0, 115.0, 110.0, 117.0, 152.0, 167.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 172.0, 268.0, 148.0, 154.0, 128.0, 145.0, 200.0, 145.0], "value": 166.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 229.0, 238.0, 202.0, 195.0, 200.0, 196.0, 195.0, 195.0, 193.0], "value": 204.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 81.0, 75.0, 167.0, 55.0, 57.0, 60.0, 70.0, 130.0, 57.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 89.0, 147.0, 77.0, 80.0, 80.0, 121.0, 80.0, 80.0, 122.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 102.0, 135.0, 87.0, 90.0, 87.0, 110.0, 130.0, 103.0, 131.0], "value": 108.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 106.0, 104.0, 96.0, 96.0, 94.0, 95.0, 96.0, 96.0, 94.0], "value": 102.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 104.0, 146.0, 106.0, 106.0, 91.0, 89.0, 91.0, 90.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 58.0, 59.0, 59.0, 60.0, 57.0, 121.0, 61.0, 59.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 78.0, 67.0, 81.0, 99.0, 99.0, 92.0, 69.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 88.0, 87.0, 109.0, 87.0, 105.0, 109.0, 77.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 132.0, 140.0, 146.0, 141.0, 134.0, 137.0, 157.0, 247.0, 146.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 165.0, 164.0, 164.0, 194.0, 162.0, 228.0, 201.0, 164.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 80.0, 87.0, 85.0, 80.0, 85.0, 84.0, 64.0, 58.0], "value": 75.3, "unit": "score"}]}]} 06:26:48 INFO - Return code: 0 06:26:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:26:48 ERROR - # TBPL SUCCESS # 06:26:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:26:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:26:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:26:48 INFO - 2016-05-13 06:26:48.194 screenresolution[1685:11407] starting screenresolution argv=screenresolution get 06:26:48 INFO - 2016-05-13 06:26:48.197 screenresolution[1685:11407] Display 0: 1600x1200x32@60 06:26:48 INFO - 2016-05-13 06:26:48.204 screenresolution[1686:11410] starting screenresolution argv=screenresolution list 06:26:48 INFO - Available Modes on Display 0 06:26:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:26:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:26:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:26:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:26:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:26:48 INFO - Intel Iris: 06:26:48 INFO - Chipset Model: Intel Iris 06:26:48 INFO - Type: GPU 06:26:48 INFO - Bus: Built-In 06:26:48 INFO - VRAM (Dynamic, Max): 1536 MB 06:26:48 INFO - Vendor: Intel (0x8086) 06:26:48 INFO - Device ID: 0x0a2e 06:26:48 INFO - Revision ID: 0x0009 06:26:48 INFO - Displays: 06:26:48 INFO - Display: 06:26:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:26:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:26:48 INFO - Display Serial Number: 1600x1200 60 06:26:48 INFO - Main Display: Yes 06:26:48 INFO - Mirror: Off 06:26:48 INFO - Online: Yes 06:26:48 INFO - Rotation: Supported 06:26:48 INFO - Return code: 0 06:26:48 INFO - Running post-action listener: _resource_record_post_action 06:26:48 INFO - ##### 06:26:48 INFO - ##### Finished run-tests step (success) 06:26:48 INFO - ##### 06:26:48 INFO - Running post-run listener: _resource_record_post_run 06:26:48 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 32583168; Write bytes: 697479680; Read time: 377; Write time: 1903 06:26:48 INFO - TinderboxPrint: CPU usage
54.7% 06:26:48 INFO - TinderboxPrint: I/O read bytes / time
32,583,168 / 377 06:26:48 INFO - TinderboxPrint: I/O write bytes / time
697,479,680 / 1,903 06:26:48 INFO - TinderboxPrint: CPU idle
2,193.5 (45.3%) 06:26:48 INFO - TinderboxPrint: CPU system
979.7 (20.3%) 06:26:48 INFO - TinderboxPrint: CPU user
1,664.5 (34.4%) 06:26:48 INFO - TinderboxPrint: Swap in / out
424,284,160 / 0 06:26:48 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 380408320; Write bytes: 374662656; Read time: 28373; Write time: 685 06:26:48 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 31661568; Write bytes: 300055552; Read time: 364; Write time: 1167 06:26:49 INFO - Running post-run listener: _upload_blobber_files 06:26:49 INFO - Blob upload gear active. 06:26:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:26:49 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 06:26:49 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'] 06:26:49 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 06:26:49 INFO - (blobuploader) - INFO - Open directory for files ... 06:26:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:26:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:26:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:50 INFO - (blobuploader) - INFO - Done attempting. 06:26:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:26:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:51 INFO - (blobuploader) - INFO - Done attempting. 06:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:26:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:53 INFO - (blobuploader) - INFO - Done attempting. 06:26:53 INFO - (blobuploader) - INFO - Iteration through files over. 06:26:53 INFO - Return code: 0 06:26:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:26:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:26:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f3771a6d7469d972bbb3bdce0747917a60970237aa1048652426fece2a4d5328a5a86b484198af950cbd09825bc31dc6e858a046c4b524fa6f989057e5b87b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1239d517a64f5faca74f84d643893ea1379000007174efb9acb17d39f412e2d791ae02109091b5d3926d43672f938019cb4f1e26def72b5d1910efa46cf33776", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e62ef62fabfbb8ae3cab5a3b9ffcc362e2b5e47e7908c823034fd25858f03219c5a99e3dc0101abd44253d78745f4e3912cf43c4e8aef859dc142aead8d26e4"} 06:26:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:26:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:26:53 INFO - Contents: 06:26:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f3771a6d7469d972bbb3bdce0747917a60970237aa1048652426fece2a4d5328a5a86b484198af950cbd09825bc31dc6e858a046c4b524fa6f989057e5b87b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1239d517a64f5faca74f84d643893ea1379000007174efb9acb17d39f412e2d791ae02109091b5d3926d43672f938019cb4f1e26def72b5d1910efa46cf33776", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e62ef62fabfbb8ae3cab5a3b9ffcc362e2b5e47e7908c823034fd25858f03219c5a99e3dc0101abd44253d78745f4e3912cf43c4e8aef859dc142aead8d26e4"} 06:26:53 INFO - Running post-run listener: copy_logs_to_upload_dir 06:26:53 INFO - Copying logs to upload dir... 06:26:53 INFO - mkdir: /builds/slave/test/build/upload/logs 06:26:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.234665 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-13 06:26:53.902265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 06:26:53.905508) ========= 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.898C1JXecE/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.9hIFVMuY6k/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f3771a6d7469d972bbb3bdce0747917a60970237aa1048652426fece2a4d5328a5a86b484198af950cbd09825bc31dc6e858a046c4b524fa6f989057e5b87b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1239d517a64f5faca74f84d643893ea1379000007174efb9acb17d39f412e2d791ae02109091b5d3926d43672f938019cb4f1e26def72b5d1910efa46cf33776", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e62ef62fabfbb8ae3cab5a3b9ffcc362e2b5e47e7908c823034fd25858f03219c5a99e3dc0101abd44253d78745f4e3912cf43c4e8aef859dc142aead8d26e4"} build_url:https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010867 build_url: 'https://queue.taskcluster.net/v1/task/OI-k6j9GQOeFe3gRz-SStg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f3771a6d7469d972bbb3bdce0747917a60970237aa1048652426fece2a4d5328a5a86b484198af950cbd09825bc31dc6e858a046c4b524fa6f989057e5b87b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1239d517a64f5faca74f84d643893ea1379000007174efb9acb17d39f412e2d791ae02109091b5d3926d43672f938019cb4f1e26def72b5d1910efa46cf33776", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e62ef62fabfbb8ae3cab5a3b9ffcc362e2b5e47e7908c823034fd25858f03219c5a99e3dc0101abd44253d78745f4e3912cf43c4e8aef859dc142aead8d26e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 06:26:53.937639) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 06:26:53.938161) ========= 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.898C1JXecE/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.9hIFVMuY6k/Listeners TMPDIR=/var/folders/_1/1pr27cr94bq03lwn54yxn2z800000w/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.004994 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 06:26:53.988148) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 06:26:53.988675) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 06:26:53.989298) ========= ========= Total master_lag: 0.18 =========