builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0145 starttime: 1463068399.98 results: success (0) buildid: 20160512060851 builduid: 972a775933a04a02960ab905716bfdbb revision: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:19.977911) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:19.978339) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:19.978619) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:19.995747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:19.996000) ========= 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.SGD19xcY83/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.kGA3nK2S9N/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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-12 08:53:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2016-05-12 08:53:20 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089704 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:20.101899) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:20.102219) ========= 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.SGD19xcY83/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.kGA3nK2S9N/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.020837 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:20.150701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 08:53:20.151000) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1e5b69b0387beb39a0b997938c07a6a05d4ae42a --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 1e5b69b0387beb39a0b997938c07a6a05d4ae42a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SGD19xcY83/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.kGA3nK2S9N/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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-12 08:53:20,222 truncating revision to first 12 chars 2016-05-12 08:53:20,222 Setting DEBUG logging. 2016-05-12 08:53:20,222 attempt 1/10 2016-05-12 08:53:20,222 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1e5b69b0387b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 08:53:21,078 unpacking tar archive at: fx-team-1e5b69b0387b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.096513 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 08:53:21.263791) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:21.264124) ========= script_repo_revision: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:21.264497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:21.264758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 08:53:21.278759) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-12 08:53:21.279028) ========= /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.SGD19xcY83/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.kGA3nK2S9N/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:21 INFO - MultiFileLogger online at 20160512 08:53:21 in /builds/slave/test 08:53:21 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 08:53:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:53:21 INFO - {'append_to_log': False, 08:53:21 INFO - 'base_work_dir': '/builds/slave/test', 08:53:21 INFO - 'blob_upload_branch': 'Fx-Team', 08:53:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:53:21 INFO - 'branch': 'Fx-Team', 08:53:21 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:21 INFO - 'config_files': ('talos/mac_config.py',), 08:53:21 INFO - 'default_actions': ('clobber', 08:53:21 INFO - 'read-buildbot-config', 08:53:21 INFO - 'download-and-extract', 08:53:21 INFO - 'populate-webroot', 08:53:21 INFO - 'create-virtualenv', 08:53:21 INFO - 'install', 08:53:21 INFO - 'run-tests'), 08:53:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:21 INFO - 'download_minidump_stackwalk': True, 08:53:21 INFO - 'download_symbols': 'ondemand', 08:53:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:53:21 INFO - 'tooltool.py': '/tools/tooltool.py', 08:53:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:53:21 INFO - '/tools/misc-python/virtualenv.py')}, 08:53:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:21 INFO - 'installer_path': 'installer.exe', 08:53:21 INFO - 'log_level': 'info', 08:53:21 INFO - 'log_name': 'talos', 08:53:21 INFO - 'log_to_console': True, 08:53:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:53:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:53:21 INFO - 'opt_config_files': (), 08:53:21 INFO - 'pip_index': False, 08:53:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:21 INFO - 'cmd': ('bash', 08:53:21 INFO - '-c', 08:53:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:21 INFO - 'enabled': True, 08:53:21 INFO - 'halt_on_failure': False, 08:53:21 INFO - 'name': 'check_screen_resolution'},), 08:53:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:53:21 INFO - 'cmd': ('bash', 08:53:21 INFO - '-c', 08:53:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:53:21 INFO - 'enabled': True, 08:53:21 INFO - 'halt_on_failure': False, 08:53:21 INFO - 'name': 'check_screen_resolution'},), 08:53:21 INFO - 'run_cmd_checks_enabled': True, 08:53:21 INFO - 'sps_profile': False, 08:53:21 INFO - 'sps_profile_interval': 0, 08:53:21 INFO - 'suite': 'dromaeojs-e10s', 08:53:21 INFO - 'system_bits': '32', 08:53:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:21 INFO - 'title': 't-yosemite-r7-0145', 08:53:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:21 INFO - 'use_talos_json': True, 08:53:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:53:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:21 INFO - 'work_dir': 'build'} 08:53:21 INFO - ##### 08:53:21 INFO - ##### Running clobber step. 08:53:21 INFO - ##### 08:53:21 INFO - Running pre-action listener: _resource_record_pre_action 08:53:21 INFO - Running main action method: clobber 08:53:21 INFO - rmtree: /builds/slave/test/build 08:53:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:53:23 INFO - Running post-action listener: _resource_record_post_action 08:53:23 INFO - ##### 08:53:23 INFO - ##### Finished clobber step (success) 08:53:23 INFO - ##### 08:53:23 INFO - ##### 08:53:23 INFO - ##### Running read-buildbot-config step. 08:53:23 INFO - ##### 08:53:23 INFO - Running pre-action listener: _resource_record_pre_action 08:53:23 INFO - Running main action method: read_buildbot_config 08:53:23 INFO - Using buildbot properties: 08:53:23 INFO - { 08:53:23 INFO - "project": "", 08:53:23 INFO - "product": "firefox", 08:53:23 INFO - "script_repo_revision": "production", 08:53:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 08:53:23 INFO - "repository": "", 08:53:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 08:53:23 INFO - "buildid": "20160512060851", 08:53:23 INFO - "pgo_build": "False", 08:53:23 INFO - "basedir": "/builds/slave/test", 08:53:23 INFO - "buildnumber": 3, 08:53:23 INFO - "slavename": "t-yosemite-r7-0145", 08:53:23 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:53:23 INFO - "platform": "yosemite_r7", 08:53:23 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 08:53:23 INFO - "branch": "fx-team", 08:53:23 INFO - "repo_path": "integration/fx-team", 08:53:23 INFO - "revision": "1e5b69b0387beb39a0b997938c07a6a05d4ae42a", 08:53:23 INFO - "stage_platform": "macosx64", 08:53:23 INFO - "builduid": "972a775933a04a02960ab905716bfdbb", 08:53:23 INFO - "slavebuilddir": "test" 08:53:23 INFO - } 08:53:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:53:23 INFO - Running post-action listener: _resource_record_post_action 08:53:23 INFO - ##### 08:53:23 INFO - ##### Finished read-buildbot-config step (success) 08:53:23 INFO - ##### 08:53:23 INFO - ##### 08:53:23 INFO - ##### Running download-and-extract step. 08:53:23 INFO - ##### 08:53:23 INFO - Running pre-action listener: _resource_record_pre_action 08:53:23 INFO - Running main action method: download_and_extract 08:53:23 INFO - mkdir: /builds/slave/test/build/tests 08:53:23 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')]} 08:53:23 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:53:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:53:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:53:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:24 INFO - Downloaded 1199 bytes. 08:53:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:53:24 INFO - Using the following test package requirements: 08:53:24 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:53:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:53:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:53:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'jsshell-mac.zip'], 08:53:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:53:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:53:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:53:24 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:53:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:53:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:53:24 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:53:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:24 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:53:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:53:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:53:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:25 INFO - Downloaded 18151124 bytes. 08:53:25 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'] 08:53:25 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 08:53:29 INFO - Return code: 0 08:53:29 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 08:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:29 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:53:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:53:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:53:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:30 INFO - Downloaded 18151124 bytes. 08:53:30 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'] 08:53:30 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 08:53:33 INFO - Return code: 0 08:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:33 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:53:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:53:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:53:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:34 INFO - Downloaded 10840239 bytes. 08:53:34 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'] 08:53:34 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 08:53:34 INFO - Return code: 0 08:53: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')]} 08:53:34 INFO - https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:53:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:53:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:53:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:53:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/InOErNaoROyc7H_QE09d1Q/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 08:53:37 INFO - Downloaded 109819449 bytes. 08:53:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:53:37 INFO - mkdir: /builds/slave/test/properties 08:53:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:53:37 INFO - Writing to file /builds/slave/test/properties/build_url 08:53:37 INFO - Contents: 08:53:37 INFO - build_url:https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:53:39 INFO - Running post-action listener: _resource_record_post_action 08:53:39 INFO - Running post-action listener: set_extra_try_arguments 08:53:39 INFO - ##### 08:53:39 INFO - ##### Finished download-and-extract step (success) 08:53:39 INFO - ##### 08:53:39 INFO - ##### 08:53:39 INFO - ##### Running populate-webroot step. 08:53:39 INFO - ##### 08:53:39 INFO - Running pre-action listener: _resource_record_pre_action 08:53:39 INFO - Running main action method: populate_webroot 08:53:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:53:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:53:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:53:39 INFO - '--tppagecycles', 08:53:39 INFO - '7'], 08:53:39 INFO - 'tests': ['tsvgm']}}, 08:53:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:53:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:53:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:53:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:53:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:53:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['damp', 'tps']}, 08:53:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'tests': ['damp', 'tps']}, 08:53:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['dromaeo_dom']}, 08:53:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:53:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['a11yr', 08:53:39 INFO - 'ts_paint', 08:53:39 INFO - 'tpaint', 08:53:39 INFO - 'sessionrestore', 08:53:39 INFO - 'sessionrestore_no_auto_restore', 08:53:39 INFO - 'tabpaint']}, 08:53:39 INFO - 'other-e10s': {'tests': ['a11yr', 08:53:39 INFO - 'ts_paint', 08:53:39 INFO - 'tpaint', 08:53:39 INFO - 'sessionrestore', 08:53:39 INFO - 'sessionrestore_no_auto_restore', 08:53:39 INFO - 'tabpaint']}, 08:53:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:53:39 INFO - 'ts_paint', 08:53:39 INFO - 'tpaint', 08:53:39 INFO - 'sessionrestore', 08:53:39 INFO - 'sessionrestore_no_auto_restore', 08:53:39 INFO - 'tabpaint']}, 08:53:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:53:39 INFO - 'ts_paint', 08:53:39 INFO - 'tpaint', 08:53:39 INFO - 'sessionrestore', 08:53:39 INFO - 'sessionrestore_no_auto_restore', 08:53:39 INFO - 'tabpaint']}, 08:53:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['a11yr', 08:53:39 INFO - 'ts_paint', 08:53:39 INFO - 'tpaint', 08:53:39 INFO - 'sessionrestore', 08:53:39 INFO - 'sessionrestore_no_auto_restore', 08:53:39 INFO - 'tabpaint']}, 08:53:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['a11yr', 08:53:39 INFO - 'ts_paint', 08:53:39 INFO - 'tpaint', 08:53:39 INFO - 'sessionrestore', 08:53:39 INFO - 'sessionrestore_no_auto_restore', 08:53:39 INFO - 'tabpaint']}, 08:53:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['tsvgx', 08:53:39 INFO - 'tsvgr_opacity', 08:53:39 INFO - 'tart', 08:53:39 INFO - 'tscrollx', 08:53:39 INFO - 'cart']}, 08:53:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:53:39 INFO - 'tsvgr_opacity', 08:53:39 INFO - 'tart', 08:53:39 INFO - 'tscrollx', 08:53:39 INFO - 'cart']}, 08:53:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'talos_options': ['--disable-e10s'], 08:53:39 INFO - 'tests': ['tp5o']}, 08:53:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'tests': ['tp5o']}, 08:53:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'talos_options': ['--disable-e10s', 08:53:39 INFO - '--xperf_path', 08:53:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:39 INFO - 'tests': ['tp5n']}, 08:53:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:53:39 INFO - 'talos_options': ['--xperf_path', 08:53:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:53:39 INFO - 'tests': ['tp5n']}}, 08:53:39 INFO - 'talos.zip': {'path': '', 08:53:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:53:39 INFO - Running post-action listener: _resource_record_post_action 08:53:39 INFO - ##### 08:53:39 INFO - ##### Finished populate-webroot step (success) 08:53:39 INFO - ##### 08:53:39 INFO - ##### 08:53:39 INFO - ##### Running create-virtualenv step. 08:53:39 INFO - ##### 08:53:39 INFO - Running pre-action listener: _resource_record_pre_action 08:53:39 INFO - Running main action method: create_virtualenv 08:53:39 INFO - Creating virtualenv /builds/slave/test/build/venv 08:53:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:53:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:53:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:53:39 INFO - Using real prefix '/tools/python27' 08:53:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:53:39 INFO - Installing distribute.............................................................................................................................................................................................done. 08:53:42 INFO - Installing pip.................done. 08:53:42 INFO - Return code: 0 08:53:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:42 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 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:42 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 08:53:42 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 08:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:42 INFO - 'HOME': '/Users/cltbld', 08:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:42 INFO - 'LOGNAME': 'cltbld', 08:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:42 INFO - 'MOZ_NO_REMOTE': '1', 08:53:42 INFO - 'NO_EM_RESTART': '1', 08:53:42 INFO - 'PAGER': '/bin/cat', 08:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:42 INFO - 'PWD': '/builds/slave/test', 08:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:42 INFO - 'SHELL': '/bin/bash', 08:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:53:42 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:42 INFO - 'USER': 'cltbld', 08:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:42 INFO - 'XPC_FLAGS': '0x0', 08:53:42 INFO - 'XPC_SERVICE_NAME': '0', 08:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:42 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 08:53:42 INFO - Cleaning up... 08:53:42 INFO - Return code: 0 08:53:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:53: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')]} 08:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53: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')]} 08:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:42 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:42 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>=0.7.1'] in /builds/slave/test/build 08:53:42 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>=0.7.1 08:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:42 INFO - 'HOME': '/Users/cltbld', 08:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:42 INFO - 'LOGNAME': 'cltbld', 08:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:42 INFO - 'MOZ_NO_REMOTE': '1', 08:53:42 INFO - 'NO_EM_RESTART': '1', 08:53:42 INFO - 'PAGER': '/bin/cat', 08:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:42 INFO - 'PWD': '/builds/slave/test', 08:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:42 INFO - 'SHELL': '/bin/bash', 08:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:53:42 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:42 INFO - 'USER': 'cltbld', 08:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:42 INFO - 'XPC_FLAGS': '0x0', 08:53:42 INFO - 'XPC_SERVICE_NAME': '0', 08:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:43 INFO - Downloading/unpacking psutil>=0.7.1 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:53:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:46 INFO - Installing collected packages: psutil 08:53:46 INFO - Running setup.py install for psutil 08:53:46 INFO - building 'psutil._psutil_osx' extension 08:53:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:53:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:53:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:53:46 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 08:53:46 INFO - building 'psutil._psutil_posix' extension 08:53:46 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 08:53:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:53:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:53:46 INFO - ^ 08:53:46 INFO - 1 warning generated. 08:53:46 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 08:53:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:53:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:53:47 INFO - Successfully installed psutil 08:53:47 INFO - Cleaning up... 08:53:47 INFO - Return code: 0 08:53:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:47 INFO - 'HOME': '/Users/cltbld', 08:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:47 INFO - 'LOGNAME': 'cltbld', 08:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:47 INFO - 'MOZ_NO_REMOTE': '1', 08:53:47 INFO - 'NO_EM_RESTART': '1', 08:53:47 INFO - 'PAGER': '/bin/cat', 08:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:47 INFO - 'PWD': '/builds/slave/test', 08:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:47 INFO - 'SHELL': '/bin/bash', 08:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:53:47 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:47 INFO - 'USER': 'cltbld', 08:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:47 INFO - 'XPC_FLAGS': '0x0', 08:53:47 INFO - 'XPC_SERVICE_NAME': '0', 08:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:53:47 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 08:53:47 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 08:53:47 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 08:53:47 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 08:53:47 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 08:53:47 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 08:53:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:53:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:53:49 INFO - Installing collected packages: mozsystemmonitor 08:53:49 INFO - Running setup.py install for mozsystemmonitor 08:53:50 INFO - Successfully installed mozsystemmonitor 08:53:50 INFO - Cleaning up... 08:53:50 INFO - Return code: 0 08:53:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:50 INFO - 'HOME': '/Users/cltbld', 08:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:50 INFO - 'LOGNAME': 'cltbld', 08:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:50 INFO - 'MOZ_NO_REMOTE': '1', 08:53:50 INFO - 'NO_EM_RESTART': '1', 08:53:50 INFO - 'PAGER': '/bin/cat', 08:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:50 INFO - 'PWD': '/builds/slave/test', 08:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:50 INFO - 'SHELL': '/bin/bash', 08:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:53:50 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:50 INFO - 'USER': 'cltbld', 08:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:50 INFO - 'XPC_FLAGS': '0x0', 08:53:50 INFO - 'XPC_SERVICE_NAME': '0', 08:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:50 INFO - Downloading/unpacking blobuploader==1.2.4 08:53:50 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 08:53:50 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 08:53:50 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 08:53:50 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 08:53:50 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 08:53:50 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 08:53:52 INFO - Downloading blobuploader-1.2.4.tar.gz 08:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:53:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 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 08:53:52 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 08:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:53:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:53:53 INFO - Downloading docopt-0.6.1.tar.gz 08:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:53:53 INFO - Installing collected packages: blobuploader, requests, docopt 08:53:53 INFO - Running setup.py install for blobuploader 08:53:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:53:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:53:54 INFO - Running setup.py install for requests 08:53:54 INFO - Running setup.py install for docopt 08:53:54 INFO - Successfully installed blobuploader requests docopt 08:53:54 INFO - Cleaning up... 08:53:54 INFO - Return code: 0 08:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:54 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 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:54 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 08:53:54 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 08:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:54 INFO - 'HOME': '/Users/cltbld', 08:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:54 INFO - 'LOGNAME': 'cltbld', 08:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:54 INFO - 'MOZ_NO_REMOTE': '1', 08:53:54 INFO - 'NO_EM_RESTART': '1', 08:53:54 INFO - 'PAGER': '/bin/cat', 08:53:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:54 INFO - 'PWD': '/builds/slave/test', 08:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:54 INFO - 'SHELL': '/bin/bash', 08:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:53:54 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:54 INFO - 'USER': 'cltbld', 08:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:54 INFO - 'XPC_FLAGS': '0x0', 08:53:54 INFO - 'XPC_SERVICE_NAME': '0', 08:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-nnL9dx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-DCwHze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-e23keC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Zgg_Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-1k2qiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-xqWwjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-_qpEIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-yDSbrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:53:55 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-fMASPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-oE1LMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-A1rrj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-KC9Exr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-j7AWZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-jh_7k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-Y4tdBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-4Ibusu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:53:56 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-6GBCbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:53:56 INFO - Running setup.py install for manifestparser 08:53:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozcrash 08:53:57 INFO - Running setup.py install for mozdebug 08:53:57 INFO - Running setup.py install for mozdevice 08:53:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozfile 08:53:57 INFO - Running setup.py install for mozhttpd 08:53:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozinfo 08:53:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:53:57 INFO - Running setup.py install for mozInstall 08:53:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for mozleak 08:53:58 INFO - Running setup.py install for mozlog 08:53:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for moznetwork 08:53:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for mozprocess 08:53:58 INFO - Running setup.py install for mozprofile 08:53:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:53:58 INFO - Running setup.py install for mozrunner 08:53:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:53:59 INFO - Running setup.py install for mozscreenshot 08:53:59 INFO - Running setup.py install for moztest 08:53:59 INFO - Running setup.py install for mozversion 08:53:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:53:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:53:59 INFO - Cleaning up... 08:53:59 INFO - Return code: 0 08:53:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:53:59 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 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:53:59 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 08:53:59 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 08:53:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:53:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:53:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:53:59 INFO - 'HOME': '/Users/cltbld', 08:53:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:53:59 INFO - 'LOGNAME': 'cltbld', 08:53:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:53:59 INFO - 'MOZ_NO_REMOTE': '1', 08:53:59 INFO - 'NO_EM_RESTART': '1', 08:53:59 INFO - 'PAGER': '/bin/cat', 08:53:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:53:59 INFO - 'PWD': '/builds/slave/test', 08:53:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:53:59 INFO - 'SHELL': '/bin/bash', 08:53:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:53:59 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:53:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:53:59 INFO - 'USER': 'cltbld', 08:53:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:53:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:53:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:53:59 INFO - 'XPC_FLAGS': '0x0', 08:53:59 INFO - 'XPC_SERVICE_NAME': '0', 08:53:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:53:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:53:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-0eyVB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:53:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-SrpQl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:53:59 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)) 08:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:53:59 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-iXRU9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-59hBR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-jKNuAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-3XI8LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-LKsbL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-PK0yAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-0AIBws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-NDMAG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-hf9EVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:00 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)) 08:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:00 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-LMEhOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-J4Zh0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ktFdwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:01 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)) 08:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-D51iwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:01 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)) 08:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-bqQnLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:01 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)) 08:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:01 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-mnlJfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 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)) 08:54:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:01 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 08:54:01 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 08:54:01 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 08:54:01 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 08:54:01 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 08:54:01 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 08:54:04 INFO - Downloading blessings-1.6.tar.gz 08:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:04 INFO - Installing collected packages: blessings 08:54:04 INFO - Running setup.py install for blessings 08:54:04 INFO - Successfully installed blessings 08:54:04 INFO - Cleaning up... 08:54:04 INFO - Return code: 0 08:54:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:04 INFO - Reading from file tmpfile_stdout 08:54:04 INFO - Current package versions: 08:54:04 INFO - blessings == 1.6 08:54:04 INFO - blobuploader == 1.2.4 08:54:04 INFO - docopt == 0.6.1 08:54:04 INFO - manifestparser == 1.1 08:54:04 INFO - mozInstall == 1.12 08:54:04 INFO - mozcrash == 0.17 08:54:04 INFO - mozdebug == 0.1 08:54:04 INFO - mozdevice == 0.48 08:54:04 INFO - mozfile == 1.2 08:54:04 INFO - mozhttpd == 0.7 08:54:04 INFO - mozinfo == 0.9 08:54:04 INFO - mozleak == 0.1 08:54:04 INFO - mozlog == 3.2 08:54:04 INFO - moznetwork == 0.27 08:54:04 INFO - mozprocess == 0.22 08:54:04 INFO - mozprofile == 0.28 08:54:04 INFO - mozrunner == 6.11 08:54:04 INFO - mozscreenshot == 0.1 08:54:04 INFO - mozsystemmonitor == 0.0 08:54:04 INFO - moztest == 0.7 08:54:04 INFO - mozversion == 1.4 08:54:04 INFO - psutil == 3.1.1 08:54:04 INFO - requests == 1.2.3 08:54:04 INFO - wsgiref == 0.1.2 08:54:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:04 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')]} 08:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:04 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')]} 08:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:04 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 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:54:04 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 08:54:04 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 08:54:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:54:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:04 INFO - 'HOME': '/Users/cltbld', 08:54:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:04 INFO - 'LOGNAME': 'cltbld', 08:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:04 INFO - 'MOZ_NO_REMOTE': '1', 08:54:04 INFO - 'NO_EM_RESTART': '1', 08:54:04 INFO - 'PAGER': '/bin/cat', 08:54:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:04 INFO - 'PWD': '/builds/slave/test', 08:54:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:04 INFO - 'SHELL': '/bin/bash', 08:54:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:54:04 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:54:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:04 INFO - 'USER': 'cltbld', 08:54:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:04 INFO - 'XPC_FLAGS': '0x0', 08:54:04 INFO - 'XPC_SERVICE_NAME': '0', 08:54:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:54:05 INFO - Cleaning up... 08:54:05 INFO - Return code: 0 08:54:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:05 INFO - 'HOME': '/Users/cltbld', 08:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:05 INFO - 'LOGNAME': 'cltbld', 08:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:05 INFO - 'MOZ_NO_REMOTE': '1', 08:54:05 INFO - 'NO_EM_RESTART': '1', 08:54:05 INFO - 'PAGER': '/bin/cat', 08:54:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:05 INFO - 'PWD': '/builds/slave/test', 08:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:05 INFO - 'SHELL': '/bin/bash', 08:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:54:05 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:05 INFO - 'USER': 'cltbld', 08:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:05 INFO - 'XPC_FLAGS': '0x0', 08:54:05 INFO - 'XPC_SERVICE_NAME': '0', 08:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:05 INFO - Downloading/unpacking jsonschema 08:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:54:07 INFO - Downloading/unpacking functools32 (from jsonschema) 08:54:07 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 08:54:07 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 08:54:07 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 08:54:07 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 08:54:07 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 08:54:07 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 08:54:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:54:08 INFO - warning: no files found matching '*.txt' 08:54:08 INFO - no previously-included directories found matching 'build' 08:54:08 INFO - no previously-included directories found matching 'dist' 08:54:08 INFO - no previously-included directories found matching '.git*' 08:54:08 INFO - Installing collected packages: jsonschema, functools32 08:54:08 INFO - Running setup.py install for functools32 08:54:08 INFO - warning: no files found matching '*.txt' 08:54:08 INFO - no previously-included directories found matching 'build' 08:54:08 INFO - no previously-included directories found matching 'dist' 08:54:08 INFO - no previously-included directories found matching '.git*' 08:54:08 INFO - Successfully installed jsonschema functools32 08:54:08 INFO - Cleaning up... 08:54:08 INFO - Return code: 0 08:54:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:54: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')]} 08:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54: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')]} 08:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:54: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5795651f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, '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.SGD19xcY83/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.kGA3nK2S9N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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 08:54: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', 'flake8'] in /builds/slave/test/build 08:54: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 flake8 08:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:08 INFO - 'HOME': '/Users/cltbld', 08:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:08 INFO - 'LOGNAME': 'cltbld', 08:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:08 INFO - 'MOZ_NO_REMOTE': '1', 08:54:08 INFO - 'NO_EM_RESTART': '1', 08:54:08 INFO - 'PAGER': '/bin/cat', 08:54:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:08 INFO - 'PWD': '/builds/slave/test', 08:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:08 INFO - 'SHELL': '/bin/bash', 08:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:54:08 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:08 INFO - 'USER': 'cltbld', 08:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:08 INFO - 'XPC_FLAGS': '0x0', 08:54:08 INFO - 'XPC_SERVICE_NAME': '0', 08:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:08 INFO - Downloading/unpacking flake8 08:54: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 08:54: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 08:54: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 08:54: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 08:54: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 08:54: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 08:54:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:54:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:54:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:54:12 INFO - no previously-included directories found matching 'docs/_build' 08:54:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:54:12 INFO - Running setup.py install for pep8 08:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:54:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:54:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:54:12 INFO - no previously-included directories found matching 'docs/_build' 08:54:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:54:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:54:12 INFO - Cleaning up... 08:54:12 INFO - Return code: 0 08:54:12 INFO - Running post-action listener: _resource_record_post_action 08:54:12 INFO - Running post-action listener: _start_resource_monitoring 08:54:12 INFO - Starting resource monitoring. 08:54:12 INFO - ##### 08:54:12 INFO - ##### Finished create-virtualenv step (success) 08:54:12 INFO - ##### 08:54:12 INFO - ##### 08:54:12 INFO - ##### Running install step. 08:54:12 INFO - ##### 08:54:12 INFO - Running pre-action listener: _resource_record_pre_action 08:54:12 INFO - Running main action method: install 08:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:13 INFO - Reading from file tmpfile_stdout 08:54:13 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:13 INFO - Reading from file tmpfile_stdout 08:54:13 INFO - Output received: 08:54:13 INFO - Usage: mozinstall [options] installer 08:54:13 INFO - Options: 08:54:13 INFO - -h, --help show this help message and exit 08:54:13 INFO - -d DEST, --destination=DEST 08:54:13 INFO - Directory to install application into. [default: 08:54:13 INFO - "/builds/slave/test"] 08:54:13 INFO - --app=APP Application being installed. [default: firefox] 08:54:13 INFO - mkdir: /builds/slave/test/build/application 08:54:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:54:46 INFO - Reading from file tmpfile_stdout 08:54:46 INFO - Output received: 08:54:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:54:46 INFO - Running post-action listener: _resource_record_post_action 08:54:46 INFO - ##### 08:54:46 INFO - ##### Finished install step (success) 08:54:46 INFO - ##### 08:54:46 INFO - ##### 08:54:46 INFO - ##### Running run-tests step. 08:54:46 INFO - ##### 08:54:46 INFO - Running pre-action listener: _resource_record_pre_action 08:54:46 INFO - Running main action method: run_tests 08:54:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:46 INFO - 2016-05-12 08:54:46.228 screenresolution[2203:64217] starting screenresolution argv=screenresolution get 08:54:46 INFO - 2016-05-12 08:54:46.231 screenresolution[2203:64217] Display 0: 1600x1200x32@60 08:54:46 INFO - 2016-05-12 08:54:46.237 screenresolution[2204:64220] starting screenresolution argv=screenresolution list 08:54:46 INFO - Available Modes on Display 0 08:54:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:46 INFO - Intel Iris: 08:54:46 INFO - Chipset Model: Intel Iris 08:54:46 INFO - Type: GPU 08:54:46 INFO - Bus: Built-In 08:54:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:46 INFO - Vendor: Intel (0x8086) 08:54:46 INFO - Device ID: 0x0a2e 08:54:46 INFO - Revision ID: 0x0009 08:54:46 INFO - Displays: 08:54:46 INFO - Display: 08:54:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:46 INFO - Display Serial Number: 1600x1200 60 08:54:46 INFO - Main Display: Yes 08:54:46 INFO - Mirror: Off 08:54:46 INFO - Online: Yes 08:54:46 INFO - Rotation: Supported 08:54:46 INFO - Return code: 0 08:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:46 INFO - Python 2.7.3 08:54:46 INFO - Return code: 0 08:54:46 INFO - grabbing minidump binary from tooltool 08:54: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')]} 08:54:46 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 0x10b484d30>, '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 0x10b2a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b47bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:46 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 08:54:46 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 08:54:46 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 08:54:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:46 INFO - Return code: 0 08:54:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:54:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:54:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:54:47 INFO - Return code: 0 08:54:47 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/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 08:54:47 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/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0145 --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 08:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SGD19xcY83/Render', 08:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:54:47 INFO - 'HOME': '/Users/cltbld', 08:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:54:47 INFO - 'LOGNAME': 'cltbld', 08:54:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:47 INFO - 'MOZ_NO_REMOTE': '1', 08:54:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:47 INFO - 'NO_EM_RESTART': '1', 08:54:47 INFO - 'PAGER': '/bin/cat', 08:54:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:47 INFO - 'PWD': '/builds/slave/test', 08:54:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:54:47 INFO - 'SHELL': '/bin/bash', 08:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGA3nK2S9N/Listeners', 08:54:47 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 08:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:54:47 INFO - 'USER': 'cltbld', 08:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:47 INFO - 'XPC_FLAGS': '0x0', 08:54:47 INFO - 'XPC_SERVICE_NAME': '0', 08:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:54:47 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/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--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 08:54:47 INFO - mozversion application_buildid: 20160512060851 08:54:47 INFO - mozversion application_changeset: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a 08:54:47 INFO - mozversion application_display_name: Nightly 08:54:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:47 INFO - mozversion application_name: Firefox 08:54:47 INFO - mozversion application_remotingname: firefox 08:54:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:54:47 INFO - mozversion application_vendor: Mozilla 08:54:47 INFO - mozversion application_version: 49.0a1 08:54:47 INFO - mozversion platform_buildid: 20160512060851 08:54:47 INFO - mozversion platform_changeset: 1e5b69b0387beb39a0b997938c07a6a05d4ae42a 08:54:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:54:47 INFO - mozversion platform_version: 49.0a1 08:54:47 INFO - using testdate: 1463068487 08:54:47 INFO - actual date: 1463068487 08:54:47 INFO - starting webserver on 'localhost:50585' 08:54:47 INFO - SUITE-START | Running 2 tests 08:54:47 INFO - TEST-START | dromaeo_css 08:54:47 INFO - operating with platform_type : mac_ 08:54:47 INFO - Initialising browser for dromaeo_css test... 08:54:47 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpZYDw81/profile http://localhost:50585/getInfo.html) 08:54:50 INFO - PROCESS | 2222 | __metrics Screen width/height:1600/1200 08:54:50 INFO - PROCESS | 2222 | colorDepth:24 08:54:50 INFO - PROCESS | 2222 | Browser inner width/height: 1024/689 08:54:50 INFO - PROCESS | 2222 | __metrics 08:54:50 ERROR - PROCESS | 2222 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 08:54:51 INFO - TEST-INFO | 2222: exit 0 08:54:51 INFO - Browser initialized. 08:54:51 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:51 INFO - TEST-INFO | started process 2226 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpZYDw81/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:55:03 INFO - PROCESS | 2226 | RSS: Main: 250056704 08:55:03 INFO - PROCESS | 2226 | 08:55:53 INFO - PROCESS | 2226 | 1463068553221 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] 08:58:03 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:50585/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50585/tests/dromaeo/cssquery-ext.html) 08:58:05 INFO - PROCESS | 2226 | RSS: Main: 263106560 08:58:05 INFO - PROCESS | 2226 | 09:01:07 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:50585/tests/dromaeo/cssquery-ext.html (next: http://localhost:50585/tests/dromaeo/cssquery-jquery.html) 09:01:07 INFO - PROCESS | 2226 | RSS: Main: 273805312 09:01:07 INFO - PROCESS | 2226 | 09:04:09 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:50585/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50585/tests/dromaeo/cssquery-mootools.html) 09:04:10 INFO - PROCESS | 2226 | RSS: Main: 275791872 09:04:10 INFO - PROCESS | 2226 | 09:07:12 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:50585/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50585/tests/dromaeo/cssquery-prototype.html) 09:07:12 INFO - PROCESS | 2226 | RSS: Main: 277389312 09:07:12 INFO - PROCESS | 2226 | 09:10:15 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:50585/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50585/tests/dromaeo/cssquery-yui.html) 09:10:16 INFO - PROCESS | 2226 | RSS: Main: 280182784 09:10:16 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:50585/tests/dromaeo/cssquery-yui.html (next: http://localhost:50585/tests/dromaeo/cssquery-dojo.html) 09:13:19 INFO - PROCESS | 2226 | RSS: Main: 281407488 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | __start_tp_report 09:13:19 INFO - PROCESS | 2226 | _x_x_mozilla_page_load 09:13:19 INFO - PROCESS | 2226 | _x_x_mozilla_page_load_details 09:13:19 INFO - PROCESS | 2226 | |i|pagename|runs| 09:13:19 INFO - PROCESS | 2226 | |0;dojo.html;3421.7604767666244;3423.6918677319045;3445.8277086145704;3463.792172469652;3486.564179477567;5082.84751457456;5613.635113717605;5797.565182611305;5964.433378829015;6001.339852616209;5763.82708518744;5801.390853960336;5801.941980580193;5882.32353279373;5978.6412815231115;5236.057509648262;5263.526282634562;5365.463453654623;5371.677699338033;5411.485908838674;94.75494912388113;5267.1309233976535;5322.76047577858;5337.572994160458;5345.224942383372;5319.175527793198;5330.040592693314;5333.573314134898;5340.813071542497;5380.784768609251;5336.546393556514;5361.927614477081;5362.410134885198;5383.138697808371;5415.079436495766;5216.165413533854;5295.867603309909;5308.283381743454;5324.92012619811;5359.651622644516;5675.886482270332;5765.567582431292;5809.215755872945;5832.941670583282;5876.9118463223085;6204.348543402968;6212.937870621281;6271.811845644638;6279.937200627981;6281.214848143982;6220.253569571635;6246.562740608159;6306.87386252277;6342.968285158591;6352.7141278642575;5993.910091348634;6038.607490513103;6038.909416358758;6047.274327080779;6062.3634518375375;6208.50331973441;6304.432601065926;6380.776672816522;6438.871222575616;6469;5812.564057695689;6170.722317495633;6193.721282542297;6230.314665386803;6247.656378899204;6131.264248290234;6235.8112591867875;6347.270063942613;6352.936470635327;6368.936310636835;5239.659422137625;6198.411150940653;6211.3167551569295;6251.499880009588;6268.404501572343;6223.284322302902;6235.158253635792;6274.623522588659;6288.717007734663;6291.6854157291955;4993.176125939252;5001.074801161797;5001.299818025479;5014.924776128361;5031.647784655112;4304.7632380218765;4352.630026447788;4410.955890441054;4451.443569553806;4531.207038768202;5737.196792449061;5965.3139888912465;5971.880562388815;5985.461308482258;6080;6295;6301.180846489927;6336.619802811754;6348.650824204713;6355.538226207906;5867.941320586825;5938.6436813791315;6004.0693692477735;6030.547708921761;6042.093685947143;5458.2904222451625;5508.7521061552325;5520.227168196456;5524.889502210013;5525.778968841281;6306.621602703853;6485;6502.349765023459;6506.089147519351;6516.543841931114;6223.222097237845;6257.1552840367785;6273.059041143865;6279.089532017741;6306;6435.742570297136;6444.871102577828;6460.25707043696;6464.256610489666;6481.384268494674;5942.346341902474;6006.669633170218;6299.937000629935;6307.148534947723;6316.242050953915;6103.11504831788;6222.564420490522;6290.591111577733;6307.810765677037;6315.652639104894;6254.874902502015;6259.96870015647;6303.621782693053;6304.275008374096;6315.052742088724;6114.114890844268;6123.069106197319;6218.22272215973;6257.56197066201;6297.968510157603;5710.942890571041;6143.327652471189;6150.446459818638;6169.784057558143;6182.28903676083;5522.6134170609275;6277.96861015692;6282.246130464374;6282.497400207881;6288.6855657217875;5939.910901336397;6073.848153796191;6109.938900610937;6116.327204007466;6149.169862068742 09:13:19 INFO - PROCESS | 2226 | |1;ext.html;500.5045005444611;525.3564383630054;528.6009063157315;563.0596903171703;572.8711040015997;40012.999675008105;42977;43008;43013.78493107534;43027.78486107569;43429.78285108578;44094.77952610241;44098;44182.33726494105;44208.557914420926;9744.464054477019;9878.753031174207;9942.403455792639;9987.700368988943;10110.393376397404;9747.366421182636;9845.753856153582;9848.310618256724;9881.505924703772;9882;9830.164436022947;9869.506524673774;9899.851502227473;9923.007699230091;9925.851112233322;9809.754756131118;9874.851877221776;9884.505774711271;9887.703368898872;9908.702738917844;9653.565589548487;9676.75808104803;9734.805303893883;9771.853422198601;9811.90188098117;40318.79840600808;40326.39510407346;40603.593964060274;40606.79696601498;40693.593064069275;2827.1518544436794;3054.8167109973474;3118.9181096860984;3266.281418088017;3417.0773891049525;27277.181684549494;28107.859460702566;28180.718192818014;28290.29274268139;28524;2629.500394924958;2655.1636234586235;2692.138515674985;2791.734785195403;2887.3503461721157;521086;527902;528909;530647;533233;67128.32871671318;67571;67720.3227967724;67749;67817.32182678209;37644;37853.432198517046;38861.41707874384;39625.40561891574;40000;17301.653966920592;17340;17408.39070632522;17531.474055778348;17589.91205043992;13222.867771322164;13241.337933103307;13298.667533311746;13382.197068175941;13389.062765606508;18316.358927437475;20583.38249852507;20715.482112946996;20742.27402040921;20855.061522231535;8357.164283571716;8360;8448.239658430648;8457.278797329278;8503.872441913376;909.8271328447573;1011.7420057885192;1089.2756317049073;1127.537709539085;1134.500819639356;30538.08385748431;30548;30684.53973190404;31159;31259.531107033414;1083.247143235444;1091.6015654286127;1103.332483847277;1183.1540448579135;1233.7964235900977;12226.388680565937;15729.370825166865;15892.364305427653;15956.76064859028;16162.43431479916;627474;661566;670973;676455;679127;63441.365586344466;63745;63949.36050639527;64119.717605647624;64172.67913660495;1227.9938600306941;1247.9438425270885;1393.7003544238037;1411.3225651687249;1426.850180731029;24816.131435399675;28564;30323.393532129674;30349.634266457622;32959.83520082384;53853.73073134609;54323.456765433424;54830.90338193134;54988;55158.17262740982;564207;578757;579431;580671;581823;26122.824472899145;27504.862475687492;27696.861515692293;27750.167494975183;28040.43919121647;41869.162616746886;43980.34029489624;46852.531474684816;47326.29010564913;48318.66641589272;32266.516002259516;38668;39341.6065839338;39631.60368396279;39742.205155897296;34890.302193956486;38556.614433855306;38577.61422385852;39088.41367379435;39549.80225098856;32941.50587741234;37902.43146352862;38360.616393835706;38737.418938715375;38905.41641875431;31259.53110703296;34317.31365372729;34493.82753086218;34918.825405872805;35596.84347218039;25383.36541586475;25448.363790904634;25513.61729574095;25589.19551864064;25627 09:13:19 INFO - PROCESS | 2226 | |2;jquery.html;330.33272788966286;390.39099005551344;391.13949311514676;405.87417900450845;416.5792549524979;19359.612807743855;21319.68532272415;22009.559808803813;22116.557668846614;22141.889290553543;19423.805761942378;21357.825319607455;21792.673109903364;21941.451463713416;21978.230761923336;5792.507636850873;6050.364711705273;6673.766418175364;6674.733010679567;6763.053172555846;6348.523860710442;6572.507061970347;6598.241202261729;6614.834629134262;6668.299828518008;6504.772332968347;6527.184101987252;6529.379708927668;6539.803805885831;6543.869122617545;6274.87450250997;6510.674466276714;6512.7069281882195;6526.673666316665;6543.247526534438;5612.270404847384;5623.212750214973;5648.717564121777;5652.576056795757;5656.377798442168;21927.780722192732;23398.298051058326;23696.052157913662;24885.253442396577;25963.74036259632;2008.8794672319561;2235.6758270050914;2321.814254859607;2348.5537747827857;2381.5832229359967;9247.844019497563;9581.760455988586;10039.196864250842;10068.093871551522;10297.073263406255;1738.8087310395847;1757.3497805811803;1779.4038996935913;1795.6139430022477;1829.5609053827118;93539.53230233805;121190;125606;130275.34862325722;131974.99999999904;11567.537298508134;11778.646640600795;11826.34955077479;11958.701032474257;11971.999999999825;14847.777283340758;14960.551183464513;15035.699286014218;15097.54707358781;15127;8352.707655232038;8761.80247907992;8768.123187681182;8778.473291602524;8880.911190888137;8621.741347759576;8711.47731136134;8773.956130219436;8775.078616745277;8832.056947471547;4572.862814115515;4632.490426053131;4656.674032817738;4671.883202919954;4727.409073865767;1971.7239586457908;1997.2310660395851;2010.6883433067899;2011.6781314989532;2038.8266997305395;884.3500027479797;888.942158830989;895.8790563273875;896.0994200828126;1006.7080546641538;17230.65538689219;20988.055537500848;21013.474663133544;21158.47103822417;21208.893955530122;787.456654908123;907.6913849291273;965.8744363232735;970.4759429907771;982.4350998175935;10859.402732849769;10996.536368134586;11100.125831385982;11113.333200007864;11379.829302560303;595681;606482;609817;614508;616445.7533137007;120582.397088014;142113;143405;146085.26957365146;148613.2569337168;949.5157469690507;994.4480813148627;1039.6880935719255;1091.7652704668376;1094.698957786615;18213.180406881456;18497.630047399245;18549.81450185481;18685.346012890026;18822.905885470485;46936.48313355805;49098.75450622724;49302;52584.47415525796;52974.735126325664;70245.29754702593;77594.6120269395;78061.21938780767;79831.60084199774;81104.59447702723;6789.15135608049;7465.552066876005;7675.462717609714;8016.6392512335915;8190.590470476572;11583.710407239887;12383.257004579755;12459.252444853339;12701.301428421526;12854;10753.354798712102;10761.838572421577;10802.189835762507;10904.236703430684;10912.781744365227;10648.520816563117;10862.34825910448;10916.235863489479;10938.890611093788;10959.452027398758;9882.209423245979;10942.45287735626;11077.335359878432;11306.886931130912;11446.94276528612;7356.521826081287;7453.142888567667;7472.065991751031;7535.73624923147;7682.078150621961;9672.75818104553;10991;11001.394923279297;11111.499982500969;11340.94329528347 09:13:19 INFO - PROCESS | 2226 | |3;mootools.html;1586.159335552157;1615.5961009747564;1869.280327074076;1940.9902950485255;1954.3452943264006;7789.104253010905;8109.756707298776;8652.740417787461;9187.897452305717;9201.631934722604;7459.627018649073;8834.24908882744;9002.54987250638;9025.323100767437;9029.277657787377;2416.3838221253554;2450.901963921441;2455.791257743085;2458.8278820482574;2462.839915405502;2405.7650770899854;2435.3546310227803;2455.9631605525933;2466;2466.6300054991807;2418.8185886058527;2444.0712529238863;2459.5326887891333;2467.0008646498195;2467.259822053377;1824.607709342491;2417.8394370702163;2432.3189506937997;2436.5492383908945;2443.7922776564014;2353.3881190890493;2389.522095580891;2409.8915548800346;2410.6625072489865;2425.805935525171;8938.821223575556;8981.461112333282;9077;9086.77283067922;9115.407498512575;5318.680879147263;5390.326209223847;5476.479734425223;5487.972560137214;5501.889962200734;3584.193556449804;3607.5851277103206;3642.7450078494517;3681.5766186888313;3710.962890371116;1032.5972870580479;1062.957481700731;1065.3554599467366;1067.8238090715022;1075.9946200269028;11577.363245021521;11945.223560468541;12051.337176455292;12100.757984840342;12120.515179392814;4836.492168322346;4872.025594881038;4996.575291100297;5016.072026675077;5034.874128146826;9827.950860245654;9842.409455432697;10159.238057145743;10179.032991865908;10243;4659.301104834234;5356.571474282048;5404.135338345845;5450.4549545045975;5478.233047373371;4415.337699345124;4801.231802911518;4944.307796908363;4962.8833512459405;4994;4023.6982226333143;4049.5140583129623;4088.202800453883;4129.132882094794;4158.792060396968;1359.8844098251561;1368.2543014057362;1369.0690330575105;1369.2127026959538;1387;891.2157301574703;895.9731208063769;911.4485736129678;913.1690161952591;936.5270538378212;5892.558058145656;5938.435848594377;5943.70281485924;6047.183630209954;6053.485453736482;395.7051996262745;438.7279886470345;460.98156073757343;462.6275847942439;483.47301441428306;3398;3744.895255899516;3980.980095099564;4022.738521996061;4051.108756073659;8340.958295208606;10900.563977440814;10912.399818010086;11060.834087488694;11060.889391105986;9306;10390.116840068678;10718.571257149626;10972.670819875417;11020.669379918616;7393.778186654409;8334.249917507332;8404.873926891101;8419.200175983395;8621.353398495137;5137.640365174551;6007.279126504847;6056.697165141637;6133.723982420891;6220.502359811295;10065.345752526062;10144.74638134023;10156.847647285149;10397.480125993821;10409.115225205942;9998.200143988319;10143;10250.897491024994;10323.380597164194;10509.421981791074;6854.554453960476;7623.809404764926;7669.9616501917135;7694.4229182810395;7797.610119494116;8510.148985101441;8543.401961862859;8593.183647553473;8638.093000235136;8648.481091134552;7448.180700123091;7585.317321441097;7629.504082234637;7644.350230230492;7705.576193309422;7368.926310736824;7423.777286681408;7465.216152303929;7474.663640136098;7625;7087.149542054986;7128.714851405885;7159.069320988447;7173.605451700207;7186.532875363085;4869.586085182799;5019.372578427697;5059.569936555434;5083.593312534915;5087.364079490064;5006.249062640487;5025.899482010413;5088.2876397305145;5121.1550094234035;5290.444503327095 09:13:19 INFO - PROCESS | 2226 | |4;prototype.html;264.64008947830945;317.7955548471294;327.4793079004384;330.39537646107624;330.72834949618715;5350.946490535093;5430.83707488775;5505.752241149148;5627.465390787879;5679.375268720442;5443.454715312709;5460.372057213422;5507.796881247503;5546.889062218764;5561.8320152767965;3303.620083690382;3583.838727257267;3630.43728222126;3978.065154688653;4029.758214507124;3650.3976843820737;4093.263212621727;4175.728577642459;4179.707420480568;4195.979020104895;3634.2368102698465;3941.1723538057045;4181.620065378418;4188.602082802129;4230.57694230576;3491.6857482826413;3822.311983842907;4159.438475805788;4198.895027624319;4233.301505251645;3547.6984456321243;3564.48314994327;3580.3018411409785;3599.5140656011367;3602.35157671619;5269.12832506285;5302.469753024667;5308.522232999048;5316.521513063804;5316.9734151329385;4114.341705327163;4582.098429151898;4612.377329060568;4684.906301873977;4745.572898439157;5098.337216161913;5792.478676919098;5803.506701930341;5807.5644326675665;5847.783718954558;3673.742838001341;3885.2423777363424;3905.804709764529;3927.56796752357;3958.782266975315;4946.749229100715;5646.858828529279;5776.971115144397;6170.6297622142365;6181.289151747562;3980.343243364812;4013.8996525086523;4020.638142567183;4037.717359784787;4054.3310353791294;4082.2039702257775;4088.5707000765087;4091.977005748563;4094.9385759213637;4104.548499665035;4729.929051064237;4749.738764367993;4763.785629646605;4776.898164807473;4778.228627396102;4781.928271075936;5790.594658373873;5793.392973162094;5814.215080964101;5829.531428799857;1352.5401363536446;1358.483776165051;1383.038788042301;1387.438087574534;1405.9947137796526;818.5088946632093;843.2874221283006;853.3812985585525;861.814709837388;901.4816480523594;4575.496695363508;4604.930926036112;5300.734963251822;5344.572434205332;5369.838904832861;4978.975105124451;5905.704714764244;5919.911201331984;5961.4038596140035;5974.1636170935235;5123.590112791042;5288.524032837063;5326.387465441524;5359.383670877899;5361.90885554634;2607.64796752436;2618.947621047568;2727.699953005168;2855.700151484106;3115.750739940799;4659.767011649404;5170.431252562215;5852.239208902815;6092.786752463642;6279.874402511924;5708.429157084258;5811;5826.563007774434;6031.09533569968;6079.513638908877;5759.963206622764;5960.940390596212;6075.939240607538;6220.688965551795;6221.502279817604;5083.87290317745;5234.304333268294;5329.898554197675;5673.489385955284;5895.498882595028;4996.725180114983;5265.2891859599995;5422.539084177889;5714.9142762857755;5821.214136091743;5447.618666693294;5454.590905682169;6083.513318934563;6096.908546371719;6152.538559607958;2813.620161278201;3585.9282814344087;3700.9082320715993;3735.159589092541;3893.06073914193;5581.581381396492;5864.648121112747;5950.3454619992635;5975.641461512323;5979.040375014396;5065.670731402447;5640.518172004305;5785.652860828363;5789.739461724148;5836.912446313224;5402.541313845319;5581.302337207849;5845;5861.98107974385;5873.647581145145;4924.384451943507;5466.480684334982;5674.347450043298;5737.741801618854;5755.8561035974435;5580.609357344947;5812.82561523155;5823.912641310299;5841.881473561214;5875.559333050124;4892.412910450626;5275.762590683352;5361.98122356751;5734.05388110957;5772.191893135048 09:13:19 INFO - PROCESS | 2226 | |5;yui.html;671.2482020137446;723.8190452386904;736.4587028534027;755.8488302339533;762.6301243896709;15055.774163387545;15549.222538873068;15636.452724154657;15898;15909.84090159098;13977.23125228112;14332.856671433283;14361.425542978268;14378.856211437882;14379.92810035951;744.601638123604;912.7216199059268;923.7413524213231;924.4499522783966;934.1172591900656;918.2929144558685;942.688912658821;944.0842382888621;946.7396465971879;952.347641865321;984.9655262065793;999.4303247149128;1001.4592120255054;1002.0830939690175;1004.2668851738199;986.6596024371617;989.6585677941063;994.4132961552647;996.7458298133929;997.2271489595585;712.8182313510022;720.0926832191424;744.556988591787;750.1523278695095;753.081240886118;15102.773458398133;15141;15155.924220378827;15168.241587920671;15208.08751474915;12161.452734626962;12166.391680416033;12215.75568488634;12391.194572352859;12473.438695258645;9676.806463870753;10032.097111260022;10209.489525523693;10243.180545556337;11121.55513779448;3458.1354661334667;3469.4795780632853;3471.114865709228;3569.303985722785;3577.3560759063357;80643;83951;84727.57636211842;84833.15166848314;84854.57572712158;9372.250219982521;9431.245500359955;9446.527673616429;9655.08276713711;9754.414735115915;20917.849518276642;21263.255786047415;21290.04194811237;21323.78676213249;21385.25151619686;7404.703811847574;7407.518511296748;7448.888266676005;7476.5887876167335;7497.353261328774;3909.4331321958157;3996.0009997500624;4001.139754952641;4017.6384125428854;4019.8191081401405;3079.4303053935096;3253.4794432890626;3440.071180781175;3489.94765078524;3541.327147841902;576.518606963182;629.7166275176097;632.6804963493406;639.7600899662626;640.3180612647516;503.65247978894445;539.7328322480397;563.8449426407689;592.6517173430481;594.2037669522774;8077.717279895293;8081;8146.714864979697;8155.266026057683;8242.505449673039;556.830166799122;559.1473003669437;567.4552429667483;592.3454582686156;595.6366616364014;3064.371803780264;3722.40441529354;3738.5700644425697;3817.522809648794;3931.1744533648252;80904.59547702225;91595;92074;93761;95320.52339738257;76418.23581764223;77210;78642.6067869657;79910;79922;55807;71498.2850171502;71775.99999999895;72639.63680181565;72835.63582182056;7729.227077292226;8078.757637270891;8121.1472795358;8198.508089514648;8243.464174828618;41478.79260603798;43976.68069957778;43999.340009899235;44146.45487407925;44602.77698611486;58844.4115558839;58860.52848678646;59698;60040;60895.782084357175;10367.32612380193;10871.130309575057;10882.238243322892;11447;11755.353455560027;12030.578929737418;12190.146689731633;12369.567065152676;12375.071869609996;12691.238525688488;11403;11438.370889601152;11720.531178752753;11791;11928.165028447926;11507.42462876836;11596.826047609462;11611.013063889664;11852.525898963944;11982.760344792881;10023.949880250551;11146.665600032013;11540.596079137189;11581.42092895335;12335.176095410452;8523.403361764616;9748.951255243679;9757.756056098655;9916.157126644028;10191.94904025475;9676.467794271382;9744.269179811401;9751;9765.462899540309;9838.803223935623 09:13:19 INFO - PROCESS | 2226 | __end_tp_report 09:13:19 INFO - PROCESS | 2226 | __start_cc_report 09:13:19 INFO - PROCESS | 2226 | _x_x_mozilla_cycle_collect,418 09:13:19 INFO - PROCESS | 2226 | __end_cc_report 09:13:19 INFO - PROCESS | 2226 | __startTimestamp1463069599316__endTimestamp 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | ------- Summary: start ------- 09:13:19 INFO - PROCESS | 2226 | Number of tests: 6 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | [#0] dojo.html Cycles:165 Average:5806.33 Median:6103.12 stddev:783.62 (12.8%) stddev-sans-first:763.37 09:13:19 INFO - PROCESS | 2226 | Values: 3421.8 3423.7 3445.8 3463.8 3486.6 5082.8 5613.6 5797.6 5964.4 6001.3 5763.8 5801.4 5801.9 5882.3 5978.6 5236.1 5263.5 5365.5 5371.7 5411.5 94.8 5267.1 5322.8 5337.6 5345.2 5319.2 5330.0 5333.6 5340.8 5380.8 5336.5 5361.9 5362.4 5383.1 5415.1 5216.2 5295.9 5308.3 5324.9 5359.7 5675.9 5765.6 5809.2 5832.9 5876.9 6204.3 6212.9 6271.8 6279.9 6281.2 6220.3 6246.6 6306.9 6343.0 6352.7 5993.9 6038.6 6038.9 6047.3 6062.4 6208.5 6304.4 6380.8 6438.9 6469.0 5812.6 6170.7 6193.7 6230.3 6247.7 6131.3 6235.8 6347.3 6352.9 6368.9 5239.7 6198.4 6211.3 6251.5 6268.4 6223.3 6235.2 6274.6 6288.7 6291.7 4993.2 5001.1 5001.3 5014.9 5031.6 4304.8 4352.6 4411.0 4451.4 4531.2 5737.2 5965.3 5971.9 5985.5 6080.0 6295.0 6301.2 6336.6 6348.7 6355.5 5867.9 5938.6 6004.1 6030.5 6042.1 5458.3 5508.8 5520.2 5524.9 5525.8 6306.6 6485.0 6502.3 6506.1 6516.5 6223.2 6257.2 6273.1 6279.1 6306.0 6435.7 6444.9 6460.3 6464.3 6481.4 5942.3 6006.7 6299.9 6307.1 6316.2 6103.1 6222.6 6290.6 6307.8 6315.7 6254.9 6260.0 6303.6 6304.3 6315.1 6114.1 6123.1 6218.2 6257.6 6298.0 5710.9 6143.3 6150.4 6169.8 6182.3 5522.6 6278.0 6282.2 6282.5 6288.7 5939.9 6073.8 6109.9 6116.3 6149.2 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | [#1] ext.html Cycles:180 Average:71904.54 Median:39587.60 stddev:158367.35 (400.0%) stddev-sans-first:158720.87 09:13:19 INFO - PROCESS | 2226 | Values: 500.5 525.4 528.6 563.1 572.9 40013.0 42977.0 43008.0 43013.8 43027.8 43429.8 44094.8 44098.0 44182.3 44208.6 9744.5 9878.8 9942.4 9987.7 10110.4 9747.4 9845.8 9848.3 9881.5 9882.0 9830.2 9869.5 9899.9 9923.0 9925.9 9809.8 9874.9 9884.5 9887.7 9908.7 9653.6 9676.8 9734.8 9771.9 9811.9 40318.8 40326.4 40603.6 40606.8 40693.6 2827.2 3054.8 3118.9 3266.3 3417.1 27277.2 28107.9 28180.7 28290.3 28524.0 2629.5 2655.2 2692.1 2791.7 2887.4 521086.0 527902.0 528909.0 530647.0 533233.0 67128.3 67571.0 67720.3 67749.0 67817.3 37644.0 37853.4 38861.4 39625.4 40000.0 17301.7 17340.0 17408.4 17531.5 17589.9 13222.9 13241.3 13298.7 13382.2 13389.1 18316.4 20583.4 20715.5 20742.3 20855.1 8357.2 8360.0 8448.2 8457.3 8503.9 909.8 1011.7 1089.3 1127.5 1134.5 30538.1 30548.0 30684.5 31159.0 31259.5 1083.2 1091.6 1103.3 1183.2 1233.8 12226.4 15729.4 15892.4 15956.8 16162.4 627474.0 661566.0 670973.0 676455.0 679127.0 63441.4 63745.0 63949.4 64119.7 64172.7 1228.0 1247.9 1393.7 1411.3 1426.9 24816.1 28564.0 30323.4 30349.6 32959.8 53853.7 54323.5 54830.9 54988.0 55158.2 564207.0 578757.0 579431.0 580671.0 581823.0 26122.8 27504.9 27696.9 27750.2 28040.4 41869.2 43980.3 46852.5 47326.3 48318.7 32266.5 38668.0 39341.6 39631.6 39742.2 34890.3 38556.6 38577.6 39088.4 39549.8 32941.5 37902.4 38360.6 38737.4 38905.4 31259.5 34317.3 34493.8 34918.8 35596.8 25383.4 25448.4 25513.6 25589.2 25627.0 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | [#2] jquery.html Cycles:180 Average:35669.10 Median:13038.82 stddev:101879.56 (781.4%) stddev-sans-first:102130.80 09:13:19 INFO - PROCESS | 2226 | Values: 330.3 390.4 391.1 405.9 416.6 19359.6 21319.7 22009.6 22116.6 22141.9 19423.8 21357.8 21792.7 21941.5 21978.2 5792.5 6050.4 6673.8 6674.7 6763.1 6348.5 6572.5 6598.2 6614.8 6668.3 6504.8 6527.2 6529.4 6539.8 6543.9 6274.9 6510.7 6512.7 6526.7 6543.2 5612.3 5623.2 5648.7 5652.6 5656.4 21927.8 23398.3 23696.1 24885.3 25963.7 2008.9 2235.7 2321.8 2348.6 2381.6 9247.8 9581.8 10039.2 10068.1 10297.1 1738.8 1757.3 1779.4 1795.6 1829.6 93539.5 121190.0 125606.0 130275.3 131975.0 11567.5 11778.6 11826.3 11958.7 11972.0 14847.8 14960.6 15035.7 15097.5 15127.0 8352.7 8761.8 8768.1 8778.5 8880.9 8621.7 8711.5 8774.0 8775.1 8832.1 4572.9 4632.5 4656.7 4671.9 4727.4 1971.7 1997.2 2010.7 2011.7 2038.8 884.4 888.9 895.9 896.1 1006.7 17230.7 20988.1 21013.5 21158.5 21208.9 787.5 907.7 965.9 970.5 982.4 10859.4 10996.5 11100.1 11113.3 11379.8 595681.0 606482.0 609817.0 614508.0 616445.8 120582.4 142113.0 143405.0 146085.3 148613.3 949.5 994.4 1039.7 1091.8 1094.7 18213.2 18497.6 18549.8 18685.3 18822.9 46936.5 49098.8 49302.0 52584.5 52974.7 70245.3 77594.6 78061.2 79831.6 81104.6 6789.2 7465.6 7675.5 8016.6 8190.6 11583.7 12383.3 12459.3 12701.3 12854.0 10753.4 10761.8 10802.2 10904.2 10912.8 10648.5 10862.3 10916.2 10938.9 10959.5 9882.2 10942.5 11077.3 11306.9 11446.9 7356.5 7453.1 7472.1 7535.7 7682.1 9672.8 10991.0 11001.4 11111.5 11340.9 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | [#3] mootools.html Cycles:180 Average:5777.88 Median:4908.17 stddev:3240.51 (66.0%) stddev-sans-first:3234.29 09:13:19 INFO - PROCESS | 2226 | Values: 1586.2 1615.6 1869.3 1941.0 1954.3 7789.1 8109.8 8652.7 9187.9 9201.6 7459.6 8834.2 9002.5 9025.3 9029.3 2416.4 2450.9 2455.8 2458.8 2462.8 2405.8 2435.4 2456.0 2466.0 2466.6 2418.8 2444.1 2459.5 2467.0 2467.3 1824.6 2417.8 2432.3 2436.5 2443.8 2353.4 2389.5 2409.9 2410.7 2425.8 8938.8 8981.5 9077.0 9086.8 9115.4 5318.7 5390.3 5476.5 5488.0 5501.9 3584.2 3607.6 3642.7 3681.6 3711.0 1032.6 1063.0 1065.4 1067.8 1076.0 11577.4 11945.2 12051.3 12100.8 12120.5 4836.5 4872.0 4996.6 5016.1 5034.9 9828.0 9842.4 10159.2 10179.0 10243.0 4659.3 5356.6 5404.1 5450.5 5478.2 4415.3 4801.2 4944.3 4962.9 4994.0 4023.7 4049.5 4088.2 4129.1 4158.8 1359.9 1368.3 1369.1 1369.2 1387.0 891.2 896.0 911.4 913.2 936.5 5892.6 5938.4 5943.7 6047.2 6053.5 395.7 438.7 461.0 462.6 483.5 3398.0 3744.9 3981.0 4022.7 4051.1 8341.0 10900.6 10912.4 11060.8 11060.9 9306.0 10390.1 10718.6 10972.7 11020.7 7393.8 8334.2 8404.9 8419.2 8621.4 5137.6 6007.3 6056.7 6133.7 6220.5 10065.3 10144.7 10156.8 10397.5 10409.1 9998.2 10143.0 10250.9 10323.4 10509.4 6854.6 7623.8 7670.0 7694.4 7797.6 8510.1 8543.4 8593.2 8638.1 8648.5 7448.2 7585.3 7629.5 7644.4 7705.6 7368.9 7423.8 7465.2 7474.7 7625.0 7087.1 7128.7 7159.1 7173.6 7186.5 4869.6 5019.4 5059.6 5083.6 5087.4 5006.2 5025.9 5088.3 5121.2 5290.4 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | [#4] prototype.html Cycles:180 Average:4643.00 Median:5305.50 stddev:1452.88 (27.4%) stddev-sans-first:1419.31 09:13:19 INFO - PROCESS | 2226 | Values: 264.6 317.8 327.5 330.4 330.7 5350.9 5430.8 5505.8 5627.5 5679.4 5443.5 5460.4 5507.8 5546.9 5561.8 3303.6 3583.8 3630.4 3978.1 4029.8 3650.4 4093.3 4175.7 4179.7 4196.0 3634.2 3941.2 4181.6 4188.6 4230.6 3491.7 3822.3 4159.4 4198.9 4233.3 3547.7 3564.5 3580.3 3599.5 3602.4 5269.1 5302.5 5308.5 5316.5 5317.0 4114.3 4582.1 4612.4 4684.9 4745.6 5098.3 5792.5 5803.5 5807.6 5847.8 3673.7 3885.2 3905.8 3927.6 3958.8 4946.7 5646.9 5777.0 6170.6 6181.3 3980.3 4013.9 4020.6 4037.7 4054.3 4082.2 4088.6 4092.0 4094.9 4104.5 4729.9 4749.7 4763.8 4776.9 4778.2 4781.9 5790.6 5793.4 5814.2 5829.5 1352.5 1358.5 1383.0 1387.4 1406.0 818.5 843.3 853.4 861.8 901.5 4575.5 4604.9 5300.7 5344.6 5369.8 4979.0 5905.7 5919.9 5961.4 5974.2 5123.6 5288.5 5326.4 5359.4 5361.9 2607.6 2618.9 2727.7 2855.7 3115.8 4659.8 5170.4 5852.2 6092.8 6279.9 5708.4 5811.0 5826.6 6031.1 6079.5 5760.0 5960.9 6075.9 6220.7 6221.5 5083.9 5234.3 5329.9 5673.5 5895.5 4996.7 5265.3 5422.5 5714.9 5821.2 5447.6 5454.6 6083.5 6096.9 6152.5 2813.6 3585.9 3700.9 3735.2 3893.1 5581.6 5864.6 5950.3 5975.6 5979.0 5065.7 5640.5 5785.7 5789.7 5836.9 5402.5 5581.3 5845.0 5862.0 5873.6 4924.4 5466.5 5674.3 5737.7 5755.9 5580.6 5812.8 5823.9 5841.9 5875.6 4892.4 5275.8 5362.0 5734.1 5772.2 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - PROCESS | 2226 | [#5] yui.html Cycles:180 Average:17950.55 Median:29710.47 stddev:25169.80 (84.7%) stddev-sans-first:25206.97 09:13:19 INFO - PROCESS | 2226 | Values: 671.2 723.8 736.5 755.8 762.6 15055.8 15549.2 15636.5 15898.0 15909.8 13977.2 14332.9 14361.4 14378.9 14379.9 744.6 912.7 923.7 924.4 934.1 918.3 942.7 944.1 946.7 952.3 985.0 999.4 1001.5 1002.1 1004.3 986.7 989.7 994.4 996.7 997.2 712.8 720.1 744.6 750.2 753.1 15102.8 15141.0 15155.9 15168.2 15208.1 12161.5 12166.4 12215.8 12391.2 12473.4 9676.8 10032.1 10209.5 10243.2 11121.6 3458.1 3469.5 3471.1 3569.3 3577.4 80643.0 83951.0 84727.6 84833.2 84854.6 9372.3 9431.2 9446.5 9655.1 9754.4 20917.8 21263.3 21290.0 21323.8 21385.3 7404.7 7407.5 7448.9 7476.6 7497.4 3909.4 3996.0 4001.1 4017.6 4019.8 3079.4 3253.5 3440.1 3489.9 3541.3 576.5 629.7 632.7 639.8 640.3 503.7 539.7 563.8 592.7 594.2 8077.7 8081.0 8146.7 8155.3 8242.5 556.8 559.1 567.5 592.3 595.6 3064.4 3722.4 3738.6 3817.5 3931.2 80904.6 91595.0 92074.0 93761.0 95320.5 76418.2 77210.0 78642.6 79910.0 79922.0 55807.0 71498.3 71776.0 72639.6 72835.6 7729.2 8078.8 8121.1 8198.5 8243.5 41478.8 43976.7 43999.3 44146.5 44602.8 58844.4 58860.5 59698.0 60040.0 60895.8 10367.3 10871.1 10882.2 11447.0 11755.4 12030.6 12190.1 12369.6 12375.1 12691.2 11403.0 11438.4 11720.5 11791.0 11928.2 11507.4 11596.8 11611.0 11852.5 11982.8 10023.9 11146.7 11540.6 11581.4 12335.2 8523.4 9749.0 9757.8 9916.2 10191.9 9676.5 9744.3 9751.0 9765.5 9838.8 09:13:19 INFO - PROCESS | 2226 | -------- Summary: end -------- 09:13:19 INFO - PROCESS | 2226 | 09:13:19 INFO - TEST-INFO | 2226: exit 0 09:13:19 INFO - TEST-OK | dromaeo_css | took 1112051ms 09:13:19 INFO - TEST-START | kraken 09:13:19 INFO - operating with platform_type : mac_ 09:13:19 INFO - Initialising browser for kraken test... 09:13:19 INFO - TEST-INFO | started process 2340 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpT0Xa67/profile http://localhost:50585/getInfo.html) 09:13:22 INFO - PROCESS | 2340 | __metrics Screen width/height:1600/1200 09:13:22 INFO - PROCESS | 2340 | colorDepth:24 09:13:22 INFO - PROCESS | 2340 | Browser inner width/height: 1024/689 09:13:22 INFO - PROCESS | 2340 | __metrics 09:13:23 ERROR - PROCESS | 2340 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 09:13:23 INFO - TEST-INFO | 2340: exit 0 09:13:23 INFO - Browser initialized. 09:13:23 INFO - Running cycle 1/1 for kraken test... 09:13:23 INFO - TEST-INFO | started process 2344 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpT0Xa67/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:35 INFO - PROCESS | 2344 | RSS: Main: 249888768 09:13:35 INFO - PROCESS | 2344 | 09:13:38 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50585/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:39 INFO - PROCESS | 2344 | RSS: Main: 261685248 09:13:39 INFO - PROCESS | 2344 | 09:13:42 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50585/tests/kraken/driver.html?testName=audio-dft) 09:13:42 INFO - PROCESS | 2344 | RSS: Main: 266944512 09:13:42 INFO - PROCESS | 2344 | 09:13:46 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50585/tests/kraken/driver.html?testName=audio-fft) 09:13:47 INFO - PROCESS | 2344 | RSS: Main: 266829824 09:13:47 INFO - PROCESS | 2344 | 09:13:49 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50585/tests/kraken/driver.html?testName=audio-oscillator) 09:13:49 INFO - PROCESS | 2344 | RSS: Main: 267497472 09:13:49 INFO - PROCESS | 2344 | 09:13:51 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50585/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:52 INFO - PROCESS | 2344 | RSS: Main: 268525568 09:13:52 INFO - PROCESS | 2344 | 09:13:55 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50585/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:56 INFO - PROCESS | 2344 | RSS: Main: 268685312 09:13:56 INFO - PROCESS | 2344 | 09:13:59 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50585/tests/kraken/driver.html?testName=imaging-desaturate) 09:14:00 INFO - PROCESS | 2344 | RSS: Main: 269488128 09:14:00 INFO - PROCESS | 2344 | 09:14:02 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50585/tests/kraken/driver.html?testName=json-parse-financial) 09:14:03 INFO - PROCESS | 2344 | RSS: Main: 269193216 09:14:03 INFO - PROCESS | 2344 | 09:14:05 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50585/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:14:05 INFO - PROCESS | 2344 | RSS: Main: 268369920 09:14:05 INFO - PROCESS | 2344 | 09:14:07 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:14:08 INFO - PROCESS | 2344 | RSS: Main: 268873728 09:14:08 INFO - PROCESS | 2344 | 09:14:10 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:14:11 INFO - PROCESS | 2344 | RSS: Main: 268513280 09:14:11 INFO - PROCESS | 2344 | 09:14:14 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:14:15 INFO - PROCESS | 2344 | RSS: Main: 268632064 09:14:15 INFO - PROCESS | 2344 | 09:14:18 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:19 INFO - PROCESS | 2344 | RSS: Main: 269291520 09:14:19 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | Cycle 1(1): loaded http://localhost:50585/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50585/tests/kraken/driver.html?testName=ai-astar) 09:14:21 INFO - PROCESS | 2344 | RSS: Main: 271171584 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | __start_tp_report 09:14:21 INFO - PROCESS | 2344 | _x_x_mozilla_page_load 09:14:21 INFO - PROCESS | 2344 | _x_x_mozilla_page_load_details 09:14:21 INFO - PROCESS | 2344 | |i|pagename|runs| 09:14:21 INFO - PROCESS | 2344 | |0;ai-astar;115;117;109;115;117;115;108;116;116;95 09:14:21 INFO - PROCESS | 2344 | |1;audio-beat-detection;153;121;143;194;121;142;125;156;157;121 09:14:21 INFO - PROCESS | 2344 | |2;audio-dft;201;237;202;276;270;200;258;191;187;239 09:14:21 INFO - PROCESS | 2344 | |3;audio-fft;68;89;74;179;59;59;57;67;131;57 09:14:21 INFO - PROCESS | 2344 | |4;audio-oscillator;74;110;203;65;100;107;159;85;82;124 09:14:21 INFO - PROCESS | 2344 | |5;imaging-gaussian-blur;89;87;88;86;88;101;131;83;136;128 09:14:21 INFO - PROCESS | 2344 | |6;imaging-darkroom;93;96;95;103;144;93;96;94;94;94 09:14:21 INFO - PROCESS | 2344 | |7;imaging-desaturate;90;90;91;89;91;90;89;89;93;92 09:14:21 INFO - PROCESS | 2344 | |8;json-parse-financial;60;61;59;61;62;61;62;122;61;61 09:14:21 INFO - PROCESS | 2344 | |9;json-stringify-tinderbox;65;66;75;107;109;76;71;67;67;68 09:14:21 INFO - PROCESS | 2344 | |10;stanford-crypto-aes;89;88;88;89;90;86;86;89;82;86 09:14:21 INFO - PROCESS | 2344 | |11;stanford-crypto-ccm;140;162;113;137;148;150;121;158;241;141 09:14:21 INFO - PROCESS | 2344 | |12;stanford-crypto-pbkdf2;158;180;196;222;208;226;190;210;224;236 09:14:21 INFO - PROCESS | 2344 | |13;stanford-crypto-sha256-iterative;66;69;65;77;86;87;87;86;63;64 09:14:21 INFO - PROCESS | 2344 | __end_tp_report 09:14:21 INFO - PROCESS | 2344 | __start_cc_report 09:14:21 INFO - PROCESS | 2344 | _x_x_mozilla_cycle_collect,1092 09:14:21 INFO - PROCESS | 2344 | __end_cc_report 09:14:21 INFO - PROCESS | 2344 | __startTimestamp1463069661285__endTimestamp 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | ------- Summary: start ------- 09:14:21 INFO - PROCESS | 2344 | Number of tests: 14 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#0] ai-astar Cycles:10 Average:112.30 Median:116.00 stddev:6.85 (5.9%) stddev-sans-first:7.19 09:14:21 INFO - PROCESS | 2344 | Values: 115.0 117.0 109.0 115.0 117.0 115.0 108.0 116.0 116.0 95.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:148.00 stddev:23.20 (15.7%) stddev-sans-first:24.34 09:14:21 INFO - PROCESS | 2344 | Values: 153.0 121.0 143.0 194.0 121.0 142.0 125.0 156.0 157.0 121.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#2] audio-dft Cycles:10 Average:226.10 Median:238.00 stddev:33.95 (14.3%) stddev-sans-first:34.77 09:14:21 INFO - PROCESS | 2344 | Values: 201.0 237.0 202.0 276.0 270.0 200.0 258.0 191.0 187.0 239.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#3] audio-fft Cycles:10 Average:84.00 Median:63.00 stddev:40.29 (64.0%) stddev-sans-first:42.32 09:14:21 INFO - PROCESS | 2344 | Values: 68.0 89.0 74.0 179.0 59.0 59.0 57.0 67.0 131.0 57.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#4] audio-oscillator Cycles:10 Average:110.90 Median:134.00 stddev:42.26 (31.5%) stddev-sans-first:42.67 09:14:21 INFO - PROCESS | 2344 | Values: 74.0 110.0 203.0 65.0 100.0 107.0 159.0 85.0 82.0 124.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:86.50 stddev:21.28 (24.6%) stddev-sans-first:22.07 09:14:21 INFO - PROCESS | 2344 | Values: 89.0 87.0 88.0 86.0 88.0 101.0 131.0 83.0 136.0 128.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:94.00 stddev:15.66 (16.7%) stddev-sans-first:16.39 09:14:21 INFO - PROCESS | 2344 | Values: 93.0 96.0 95.0 103.0 144.0 93.0 96.0 94.0 94.0 94.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:90.50 stddev:1.35 (1.5%) stddev-sans-first:1.42 09:14:21 INFO - PROCESS | 2344 | Values: 90.0 90.0 91.0 89.0 91.0 90.0 89.0 89.0 93.0 92.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:61.00 stddev:19.34 (31.7%) stddev-sans-first:20.35 09:14:21 INFO - PROCESS | 2344 | Values: 60.0 61.0 59.0 61.0 62.0 61.0 62.0 122.0 61.0 61.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#9] json-stringify-tinderbox Cycles:10 Average:77.10 Median:67.50 stddev:16.70 (24.7%) stddev-sans-first:17.13 09:14:21 INFO - PROCESS | 2344 | Values: 65.0 66.0 75.0 107.0 109.0 76.0 71.0 67.0 67.0 68.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:88.50 stddev:2.36 (2.7%) stddev-sans-first:2.42 09:14:21 INFO - PROCESS | 2344 | Values: 89.0 88.0 88.0 89.0 90.0 86.0 86.0 89.0 82.0 86.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:149.00 stddev:35.03 (23.5%) stddev-sans-first:36.92 09:14:21 INFO - PROCESS | 2344 | Values: 140.0 162.0 113.0 137.0 148.0 150.0 121.0 158.0 241.0 141.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.00 Median:216.00 stddev:24.10 (11.2%) stddev-sans-first:18.61 09:14:21 INFO - PROCESS | 2344 | Values: 158.0 180.0 196.0 222.0 208.0 226.0 190.0 210.0 224.0 236.0 09:14:21 INFO - PROCESS | 2344 | 09:14:21 INFO - PROCESS | 2344 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:81.50 stddev:10.62 (13.0%) stddev-sans-first:10.76 09:14:21 INFO - PROCESS | 2344 | Values: 66.0 69.0 65.0 77.0 86.0 87.0 87.0 86.0 63.0 64.0 09:14:21 INFO - PROCESS | 2344 | -------- Summary: end -------- 09:14:21 INFO - PROCESS | 2344 | 09:14:22 INFO - TEST-INFO | 2344: exit 0 09:14:22 INFO - TEST-OK | kraken | took 62118ms 09:14:22 INFO - SUITE-END | took 1174s 09:14:22 INFO - Completed test suite (00:19:34) 09:14:22 INFO - javascript benchmark 09:14:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7268.995619871112, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3421.7604767666244, 3423.6918677319045, 3445.8277086145704, 3463.792172469652, 3486.564179477567, 5082.84751457456, 5613.635113717605, 5797.565182611305, 5964.433378829015, 6001.339852616209, 5763.82708518744, 5801.390853960336, 5801.941980580193, 5882.32353279373, 5978.6412815231115, 5236.057509648262, 5263.526282634562, 5365.463453654623, 5371.677699338033, 5411.485908838674, 94.75494912388113, 5267.1309233976535, 5322.76047577858, 5337.572994160458, 5345.224942383372, 5319.175527793198, 5330.040592693314, 5333.573314134898, 5340.813071542497, 5380.784768609251, 5336.546393556514, 5361.927614477081, 5362.410134885198, 5383.138697808371, 5415.079436495766, 5216.165413533854, 5295.867603309909, 5308.283381743454, 5324.92012619811, 5359.651622644516, 5675.886482270332, 5765.567582431292, 5809.215755872945, 5832.941670583282, 5876.9118463223085, 6204.348543402968, 6212.937870621281, 6271.811845644638, 6279.937200627981, 6281.214848143982, 6220.253569571635, 6246.562740608159, 6306.87386252277, 6342.968285158591, 6352.7141278642575, 5993.910091348634, 6038.607490513103, 6038.909416358758, 6047.274327080779, 6062.3634518375375, 6208.50331973441, 6304.432601065926, 6380.776672816522, 6438.871222575616, 6469.0, 5812.564057695689, 6170.722317495633, 6193.721282542297, 6230.314665386803, 6247.656378899204, 6131.264248290234, 6235.8112591867875, 6347.270063942613, 6352.936470635327, 6368.936310636835, 5239.659422137625, 6198.411150940653, 6211.3167551569295, 6251.499880009588, 6268.404501572343, 6223.284322302902, 6235.158253635792, 6274.623522588659, 6288.717007734663, 6291.6854157291955, 4993.176125939252, 5001.074801161797, 5001.299818025479, 5014.924776128361, 5031.647784655112, 4304.7632380218765, 4352.630026447788, 4410.955890441054, 4451.443569553806, 4531.207038768202, 5737.196792449061, 5965.3139888912465, 5971.880562388815, 5985.461308482258, 6080.0, 6295.0, 6301.180846489927, 6336.619802811754, 6348.650824204713, 6355.538226207906, 5867.941320586825, 5938.6436813791315, 6004.0693692477735, 6030.547708921761, 6042.093685947143, 5458.2904222451625, 5508.7521061552325, 5520.227168196456, 5524.889502210013, 5525.778968841281, 6306.621602703853, 6485.0, 6502.349765023459, 6506.089147519351, 6516.543841931114, 6223.222097237845, 6257.1552840367785, 6273.059041143865, 6279.089532017741, 6306.0, 6435.742570297136, 6444.871102577828, 6460.25707043696, 6464.256610489666, 6481.384268494674, 5942.346341902474, 6006.669633170218, 6299.937000629935, 6307.148534947723, 6316.242050953915, 6103.11504831788, 6222.564420490522, 6290.591111577733, 6307.810765677037, 6315.652639104894, 6254.874902502015, 6259.96870015647, 6303.621782693053, 6304.275008374096, 6315.052742088724, 6114.114890844268, 6123.069106197319, 6218.22272215973, 6257.56197066201, 6297.968510157603, 5710.942890571041, 6143.327652471189, 6150.446459818638, 6169.784057558143, 6182.28903676083, 5522.6134170609275, 6277.96861015692, 6282.246130464374, 6282.497400207881, 6288.6855657217875, 5939.910901336397, 6073.848153796191, 6109.938900610937, 6116.327204007466, 6149.169862068742], "value": 5758.910532739263, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.5045005444611, 525.3564383630054, 528.6009063157315, 563.0596903171703, 572.8711040015997, 40012.999675008105, 42977.0, 43008.0, 43013.78493107534, 43027.78486107569, 43429.78285108578, 44094.77952610241, 44098.0, 44182.33726494105, 44208.557914420926, 9744.464054477019, 9878.753031174207, 9942.403455792639, 9987.700368988943, 10110.393376397404, 9747.366421182636, 9845.753856153582, 9848.310618256724, 9881.505924703772, 9882.0, 9830.164436022947, 9869.506524673774, 9899.851502227473, 9923.007699230091, 9925.851112233322, 9809.754756131118, 9874.851877221776, 9884.505774711271, 9887.703368898872, 9908.702738917844, 9653.565589548487, 9676.75808104803, 9734.805303893883, 9771.853422198601, 9811.90188098117, 40318.79840600808, 40326.39510407346, 40603.593964060274, 40606.79696601498, 40693.593064069275, 2827.1518544436794, 3054.8167109973474, 3118.9181096860984, 3266.281418088017, 3417.0773891049525, 27277.181684549494, 28107.859460702566, 28180.718192818014, 28290.29274268139, 28524.0, 2629.500394924958, 2655.1636234586235, 2692.138515674985, 2791.734785195403, 2887.3503461721157, 521086.0, 527902.0, 528909.0, 530647.0, 533233.0, 67128.32871671318, 67571.0, 67720.3227967724, 67749.0, 67817.32182678209, 37644.0, 37853.432198517046, 38861.41707874384, 39625.40561891574, 40000.0, 17301.653966920592, 17340.0, 17408.39070632522, 17531.474055778348, 17589.91205043992, 13222.867771322164, 13241.337933103307, 13298.667533311746, 13382.197068175941, 13389.062765606508, 18316.358927437475, 20583.38249852507, 20715.482112946996, 20742.27402040921, 20855.061522231535, 8357.164283571716, 8360.0, 8448.239658430648, 8457.278797329278, 8503.872441913376, 909.8271328447573, 1011.7420057885192, 1089.2756317049073, 1127.537709539085, 1134.500819639356, 30538.08385748431, 30548.0, 30684.53973190404, 31159.0, 31259.531107033414, 1083.247143235444, 1091.6015654286127, 1103.332483847277, 1183.1540448579135, 1233.7964235900977, 12226.388680565937, 15729.370825166865, 15892.364305427653, 15956.76064859028, 16162.43431479916, 627474.0, 661566.0, 670973.0, 676455.0, 679127.0, 63441.365586344466, 63745.0, 63949.36050639527, 64119.717605647624, 64172.67913660495, 1227.9938600306941, 1247.9438425270885, 1393.7003544238037, 1411.3225651687249, 1426.850180731029, 24816.131435399675, 28564.0, 30323.393532129674, 30349.634266457622, 32959.83520082384, 53853.73073134609, 54323.456765433424, 54830.90338193134, 54988.0, 55158.17262740982, 564207.0, 578757.0, 579431.0, 580671.0, 581823.0, 26122.824472899145, 27504.862475687492, 27696.861515692293, 27750.167494975183, 28040.43919121647, 41869.162616746886, 43980.34029489624, 46852.531474684816, 47326.29010564913, 48318.66641589272, 32266.516002259516, 38668.0, 39341.6065839338, 39631.60368396279, 39742.205155897296, 34890.302193956486, 38556.614433855306, 38577.61422385852, 39088.41367379435, 39549.80225098856, 32941.50587741234, 37902.43146352862, 38360.616393835706, 38737.418938715375, 38905.41641875431, 31259.53110703296, 34317.31365372729, 34493.82753086218, 34918.825405872805, 35596.84347218039, 25383.36541586475, 25448.363790904634, 25513.61729574095, 25589.19551864064, 25627.0], "value": 20134.102073635204, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.33272788966286, 390.39099005551344, 391.13949311514676, 405.87417900450845, 416.5792549524979, 19359.612807743855, 21319.68532272415, 22009.559808803813, 22116.557668846614, 22141.889290553543, 19423.805761942378, 21357.825319607455, 21792.673109903364, 21941.451463713416, 21978.230761923336, 5792.507636850873, 6050.364711705273, 6673.766418175364, 6674.733010679567, 6763.053172555846, 6348.523860710442, 6572.507061970347, 6598.241202261729, 6614.834629134262, 6668.299828518008, 6504.772332968347, 6527.184101987252, 6529.379708927668, 6539.803805885831, 6543.869122617545, 6274.87450250997, 6510.674466276714, 6512.7069281882195, 6526.673666316665, 6543.247526534438, 5612.270404847384, 5623.212750214973, 5648.717564121777, 5652.576056795757, 5656.377798442168, 21927.780722192732, 23398.298051058326, 23696.052157913662, 24885.253442396577, 25963.74036259632, 2008.8794672319561, 2235.6758270050914, 2321.814254859607, 2348.5537747827857, 2381.5832229359967, 9247.844019497563, 9581.760455988586, 10039.196864250842, 10068.093871551522, 10297.073263406255, 1738.8087310395847, 1757.3497805811803, 1779.4038996935913, 1795.6139430022477, 1829.5609053827118, 93539.53230233805, 121190.0, 125606.0, 130275.34862325722, 131974.99999999904, 11567.537298508134, 11778.646640600795, 11826.34955077479, 11958.701032474257, 11971.999999999825, 14847.777283340758, 14960.551183464513, 15035.699286014218, 15097.54707358781, 15127.0, 8352.707655232038, 8761.80247907992, 8768.123187681182, 8778.473291602524, 8880.911190888137, 8621.741347759576, 8711.47731136134, 8773.956130219436, 8775.078616745277, 8832.056947471547, 4572.862814115515, 4632.490426053131, 4656.674032817738, 4671.883202919954, 4727.409073865767, 1971.7239586457908, 1997.2310660395851, 2010.6883433067899, 2011.6781314989532, 2038.8266997305395, 884.3500027479797, 888.942158830989, 895.8790563273875, 896.0994200828126, 1006.7080546641538, 17230.65538689219, 20988.055537500848, 21013.474663133544, 21158.47103822417, 21208.893955530122, 787.456654908123, 907.6913849291273, 965.8744363232735, 970.4759429907771, 982.4350998175935, 10859.402732849769, 10996.536368134586, 11100.125831385982, 11113.333200007864, 11379.829302560303, 595681.0, 606482.0, 609817.0, 614508.0, 616445.7533137007, 120582.397088014, 142113.0, 143405.0, 146085.26957365146, 148613.2569337168, 949.5157469690507, 994.4480813148627, 1039.6880935719255, 1091.7652704668376, 1094.698957786615, 18213.180406881456, 18497.630047399245, 18549.81450185481, 18685.346012890026, 18822.905885470485, 46936.48313355805, 49098.75450622724, 49302.0, 52584.47415525796, 52974.735126325664, 70245.29754702593, 77594.6120269395, 78061.21938780767, 79831.60084199774, 81104.59447702723, 6789.15135608049, 7465.552066876005, 7675.462717609714, 8016.6392512335915, 8190.590470476572, 11583.710407239887, 12383.257004579755, 12459.252444853339, 12701.301428421526, 12854.0, 10753.354798712102, 10761.838572421577, 10802.189835762507, 10904.236703430684, 10912.781744365227, 10648.520816563117, 10862.34825910448, 10916.235863489479, 10938.890611093788, 10959.452027398758, 9882.209423245979, 10942.45287735626, 11077.335359878432, 11306.886931130912, 11446.94276528612, 7356.521826081287, 7453.142888567667, 7472.065991751031, 7535.73624923147, 7682.078150621961, 9672.75818104553, 10991.0, 11001.394923279297, 11111.499982500969, 11340.94329528347], "value": 9675.373765583228, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.159335552157, 1615.5961009747564, 1869.280327074076, 1940.9902950485255, 1954.3452943264006, 7789.104253010905, 8109.756707298776, 8652.740417787461, 9187.897452305717, 9201.631934722604, 7459.627018649073, 8834.24908882744, 9002.54987250638, 9025.323100767437, 9029.277657787377, 2416.3838221253554, 2450.901963921441, 2455.791257743085, 2458.8278820482574, 2462.839915405502, 2405.7650770899854, 2435.3546310227803, 2455.9631605525933, 2466.0, 2466.6300054991807, 2418.8185886058527, 2444.0712529238863, 2459.5326887891333, 2467.0008646498195, 2467.259822053377, 1824.607709342491, 2417.8394370702163, 2432.3189506937997, 2436.5492383908945, 2443.7922776564014, 2353.3881190890493, 2389.522095580891, 2409.8915548800346, 2410.6625072489865, 2425.805935525171, 8938.821223575556, 8981.461112333282, 9077.0, 9086.77283067922, 9115.407498512575, 5318.680879147263, 5390.326209223847, 5476.479734425223, 5487.972560137214, 5501.889962200734, 3584.193556449804, 3607.5851277103206, 3642.7450078494517, 3681.5766186888313, 3710.962890371116, 1032.5972870580479, 1062.957481700731, 1065.3554599467366, 1067.8238090715022, 1075.9946200269028, 11577.363245021521, 11945.223560468541, 12051.337176455292, 12100.757984840342, 12120.515179392814, 4836.492168322346, 4872.025594881038, 4996.575291100297, 5016.072026675077, 5034.874128146826, 9827.950860245654, 9842.409455432697, 10159.238057145743, 10179.032991865908, 10243.0, 4659.301104834234, 5356.571474282048, 5404.135338345845, 5450.4549545045975, 5478.233047373371, 4415.337699345124, 4801.231802911518, 4944.307796908363, 4962.8833512459405, 4994.0, 4023.6982226333143, 4049.5140583129623, 4088.202800453883, 4129.132882094794, 4158.792060396968, 1359.8844098251561, 1368.2543014057362, 1369.0690330575105, 1369.2127026959538, 1387.0, 891.2157301574703, 895.9731208063769, 911.4485736129678, 913.1690161952591, 936.5270538378212, 5892.558058145656, 5938.435848594377, 5943.70281485924, 6047.183630209954, 6053.485453736482, 395.7051996262745, 438.7279886470345, 460.98156073757343, 462.6275847942439, 483.47301441428306, 3398.0, 3744.895255899516, 3980.980095099564, 4022.738521996061, 4051.108756073659, 8340.958295208606, 10900.563977440814, 10912.399818010086, 11060.834087488694, 11060.889391105986, 9306.0, 10390.116840068678, 10718.571257149626, 10972.670819875417, 11020.669379918616, 7393.778186654409, 8334.249917507332, 8404.873926891101, 8419.200175983395, 8621.353398495137, 5137.640365174551, 6007.279126504847, 6056.697165141637, 6133.723982420891, 6220.502359811295, 10065.345752526062, 10144.74638134023, 10156.847647285149, 10397.480125993821, 10409.115225205942, 9998.200143988319, 10143.0, 10250.897491024994, 10323.380597164194, 10509.421981791074, 6854.554453960476, 7623.809404764926, 7669.9616501917135, 7694.4229182810395, 7797.610119494116, 8510.148985101441, 8543.401961862859, 8593.183647553473, 8638.093000235136, 8648.481091134552, 7448.180700123091, 7585.317321441097, 7629.504082234637, 7644.350230230492, 7705.576193309422, 7368.926310736824, 7423.777286681408, 7465.216152303929, 7474.663640136098, 7625.0, 7087.149542054986, 7128.714851405885, 7159.069320988447, 7173.605451700207, 7186.532875363085, 4869.586085182799, 5019.372578427697, 5059.569936555434, 5083.593312534915, 5087.364079490064, 5006.249062640487, 5025.899482010413, 5088.2876397305145, 5121.1550094234035, 5290.444503327095], "value": 4580.177734742035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.64008947830945, 317.7955548471294, 327.4793079004384, 330.39537646107624, 330.72834949618715, 5350.946490535093, 5430.83707488775, 5505.752241149148, 5627.465390787879, 5679.375268720442, 5443.454715312709, 5460.372057213422, 5507.796881247503, 5546.889062218764, 5561.8320152767965, 3303.620083690382, 3583.838727257267, 3630.43728222126, 3978.065154688653, 4029.758214507124, 3650.3976843820737, 4093.263212621727, 4175.728577642459, 4179.707420480568, 4195.979020104895, 3634.2368102698465, 3941.1723538057045, 4181.620065378418, 4188.602082802129, 4230.57694230576, 3491.6857482826413, 3822.311983842907, 4159.438475805788, 4198.895027624319, 4233.301505251645, 3547.6984456321243, 3564.48314994327, 3580.3018411409785, 3599.5140656011367, 3602.35157671619, 5269.12832506285, 5302.469753024667, 5308.522232999048, 5316.521513063804, 5316.9734151329385, 4114.341705327163, 4582.098429151898, 4612.377329060568, 4684.906301873977, 4745.572898439157, 5098.337216161913, 5792.478676919098, 5803.506701930341, 5807.5644326675665, 5847.783718954558, 3673.742838001341, 3885.2423777363424, 3905.804709764529, 3927.56796752357, 3958.782266975315, 4946.749229100715, 5646.858828529279, 5776.971115144397, 6170.6297622142365, 6181.289151747562, 3980.343243364812, 4013.8996525086523, 4020.638142567183, 4037.717359784787, 4054.3310353791294, 4082.2039702257775, 4088.5707000765087, 4091.977005748563, 4094.9385759213637, 4104.548499665035, 4729.929051064237, 4749.738764367993, 4763.785629646605, 4776.898164807473, 4778.228627396102, 4781.928271075936, 5790.594658373873, 5793.392973162094, 5814.215080964101, 5829.531428799857, 1352.5401363536446, 1358.483776165051, 1383.038788042301, 1387.438087574534, 1405.9947137796526, 818.5088946632093, 843.2874221283006, 853.3812985585525, 861.814709837388, 901.4816480523594, 4575.496695363508, 4604.930926036112, 5300.734963251822, 5344.572434205332, 5369.838904832861, 4978.975105124451, 5905.704714764244, 5919.911201331984, 5961.4038596140035, 5974.1636170935235, 5123.590112791042, 5288.524032837063, 5326.387465441524, 5359.383670877899, 5361.90885554634, 2607.64796752436, 2618.947621047568, 2727.699953005168, 2855.700151484106, 3115.750739940799, 4659.767011649404, 5170.431252562215, 5852.239208902815, 6092.786752463642, 6279.874402511924, 5708.429157084258, 5811.0, 5826.563007774434, 6031.09533569968, 6079.513638908877, 5759.963206622764, 5960.940390596212, 6075.939240607538, 6220.688965551795, 6221.502279817604, 5083.87290317745, 5234.304333268294, 5329.898554197675, 5673.489385955284, 5895.498882595028, 4996.725180114983, 5265.2891859599995, 5422.539084177889, 5714.9142762857755, 5821.214136091743, 5447.618666693294, 5454.590905682169, 6083.513318934563, 6096.908546371719, 6152.538559607958, 2813.620161278201, 3585.9282814344087, 3700.9082320715993, 3735.159589092541, 3893.06073914193, 5581.581381396492, 5864.648121112747, 5950.3454619992635, 5975.641461512323, 5979.040375014396, 5065.670731402447, 5640.518172004305, 5785.652860828363, 5789.739461724148, 5836.912446313224, 5402.541313845319, 5581.302337207849, 5845.0, 5861.98107974385, 5873.647581145145, 4924.384451943507, 5466.480684334982, 5674.347450043298, 5737.741801618854, 5755.8561035974435, 5580.609357344947, 5812.82561523155, 5823.912641310299, 5841.881473561214, 5875.559333050124, 4892.412910450626, 5275.762590683352, 5361.98122356751, 5734.05388110957, 5772.191893135048], "value": 4175.762942344683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.2482020137446, 723.8190452386904, 736.4587028534027, 755.8488302339533, 762.6301243896709, 15055.774163387545, 15549.222538873068, 15636.452724154657, 15898.0, 15909.84090159098, 13977.23125228112, 14332.856671433283, 14361.425542978268, 14378.856211437882, 14379.92810035951, 744.601638123604, 912.7216199059268, 923.7413524213231, 924.4499522783966, 934.1172591900656, 918.2929144558685, 942.688912658821, 944.0842382888621, 946.7396465971879, 952.347641865321, 984.9655262065793, 999.4303247149128, 1001.4592120255054, 1002.0830939690175, 1004.2668851738199, 986.6596024371617, 989.6585677941063, 994.4132961552647, 996.7458298133929, 997.2271489595585, 712.8182313510022, 720.0926832191424, 744.556988591787, 750.1523278695095, 753.081240886118, 15102.773458398133, 15141.0, 15155.924220378827, 15168.241587920671, 15208.08751474915, 12161.452734626962, 12166.391680416033, 12215.75568488634, 12391.194572352859, 12473.438695258645, 9676.806463870753, 10032.097111260022, 10209.489525523693, 10243.180545556337, 11121.55513779448, 3458.1354661334667, 3469.4795780632853, 3471.114865709228, 3569.303985722785, 3577.3560759063357, 80643.0, 83951.0, 84727.57636211842, 84833.15166848314, 84854.57572712158, 9372.250219982521, 9431.245500359955, 9446.527673616429, 9655.08276713711, 9754.414735115915, 20917.849518276642, 21263.255786047415, 21290.04194811237, 21323.78676213249, 21385.25151619686, 7404.703811847574, 7407.518511296748, 7448.888266676005, 7476.5887876167335, 7497.353261328774, 3909.4331321958157, 3996.0009997500624, 4001.139754952641, 4017.6384125428854, 4019.8191081401405, 3079.4303053935096, 3253.4794432890626, 3440.071180781175, 3489.94765078524, 3541.327147841902, 576.518606963182, 629.7166275176097, 632.6804963493406, 639.7600899662626, 640.3180612647516, 503.65247978894445, 539.7328322480397, 563.8449426407689, 592.6517173430481, 594.2037669522774, 8077.717279895293, 8081.0, 8146.714864979697, 8155.266026057683, 8242.505449673039, 556.830166799122, 559.1473003669437, 567.4552429667483, 592.3454582686156, 595.6366616364014, 3064.371803780264, 3722.40441529354, 3738.5700644425697, 3817.522809648794, 3931.1744533648252, 80904.59547702225, 91595.0, 92074.0, 93761.0, 95320.52339738257, 76418.23581764223, 77210.0, 78642.6067869657, 79910.0, 79922.0, 55807.0, 71498.2850171502, 71775.99999999895, 72639.63680181565, 72835.63582182056, 7729.227077292226, 8078.757637270891, 8121.1472795358, 8198.508089514648, 8243.464174828618, 41478.79260603798, 43976.68069957778, 43999.340009899235, 44146.45487407925, 44602.77698611486, 58844.4115558839, 58860.52848678646, 59698.0, 60040.0, 60895.782084357175, 10367.32612380193, 10871.130309575057, 10882.238243322892, 11447.0, 11755.353455560027, 12030.578929737418, 12190.146689731633, 12369.567065152676, 12375.071869609996, 12691.238525688488, 11403.0, 11438.370889601152, 11720.531178752753, 11791.0, 11928.165028447926, 11507.42462876836, 11596.826047609462, 11611.013063889664, 11852.525898963944, 11982.760344792881, 10023.949880250551, 11146.665600032013, 11540.596079137189, 11581.42092895335, 12335.176095410452, 8523.403361764616, 9748.951255243679, 9757.756056098655, 9916.157126644028, 10191.94904025475, 9676.467794271382, 9744.269179811401, 9751.0, 9765.462899540309, 9838.803223935623], "value": 6874.52266842283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1631.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 109.0, 115.0, 117.0, 115.0, 108.0, 116.0, 116.0, 95.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 143.0, 194.0, 121.0, 142.0, 125.0, 156.0, 157.0, 121.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 237.0, 202.0, 276.0, 270.0, 200.0, 258.0, 191.0, 187.0, 239.0], "value": 226.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 89.0, 74.0, 179.0, 59.0, 59.0, 57.0, 67.0, 131.0, 57.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 110.0, 203.0, 65.0, 100.0, 107.0, 159.0, 85.0, 82.0, 124.0], "value": 110.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 88.0, 101.0, 131.0, 83.0, 136.0, 128.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 95.0, 103.0, 144.0, 93.0, 96.0, 94.0, 94.0, 94.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 91.0, 89.0, 91.0, 90.0, 89.0, 89.0, 93.0, 92.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 61.0, 62.0, 61.0, 62.0, 122.0, 61.0, 61.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 75.0, 107.0, 109.0, 76.0, 71.0, 67.0, 67.0, 68.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 89.0, 90.0, 86.0, 86.0, 89.0, 82.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 162.0, 113.0, 137.0, 148.0, 150.0, 121.0, 158.0, 241.0, 141.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 180.0, 196.0, 222.0, 208.0, 226.0, 190.0, 210.0, 224.0, 236.0], "value": 205.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 65.0, 77.0, 86.0, 87.0, 87.0, 86.0, 63.0, 64.0], "value": 75.0, "unit": "score"}]}]} 09:14:22 INFO - Return code: 0 09:14:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:14:22 ERROR - # TBPL SUCCESS # 09:14:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:22 INFO - 2016-05-12 09:14:22.623 screenresolution[2354:67016] starting screenresolution argv=screenresolution get 09:14:22 INFO - 2016-05-12 09:14:22.626 screenresolution[2354:67016] Display 0: 1600x1200x32@60 09:14:22 INFO - 2016-05-12 09:14:22.632 screenresolution[2356:67020] starting screenresolution argv=screenresolution list 09:14:22 INFO - Available Modes on Display 0 09:14:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:22 INFO - Intel Iris: 09:14:22 INFO - Chipset Model: Intel Iris 09:14:22 INFO - Type: GPU 09:14:22 INFO - Bus: Built-In 09:14:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:22 INFO - Vendor: Intel (0x8086) 09:14:22 INFO - Device ID: 0x0a2e 09:14:22 INFO - Revision ID: 0x0009 09:14:22 INFO - Displays: 09:14:22 INFO - Display: 09:14:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:22 INFO - Display Serial Number: 1600x1200 60 09:14:22 INFO - Main Display: Yes 09:14:22 INFO - Mirror: Off 09:14:22 INFO - Online: Yes 09:14:22 INFO - Rotation: Supported 09:14:22 INFO - Return code: 0 09:14:22 INFO - Running post-action listener: _resource_record_post_action 09:14:22 INFO - ##### 09:14:22 INFO - ##### Finished run-tests step (success) 09:14:22 INFO - ##### 09:14:22 INFO - Running post-run listener: _resource_record_post_run 09:14:23 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 20480; Write bytes: 697059840; Read time: 2; Write time: 1811 09:14:23 INFO - TinderboxPrint: CPU usage
54.7% 09:14:23 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 2 09:14:23 INFO - TinderboxPrint: I/O write bytes / time
697,059,840 / 1,811 09:14:23 INFO - TinderboxPrint: CPU idle
2,191.4 (45.3%) 09:14:23 INFO - TinderboxPrint: CPU system
976.7 (20.2%) 09:14:23 INFO - TinderboxPrint: CPU user
1,669.4 (34.5%) 09:14:23 INFO - TinderboxPrint: Swap in / out
393,486,336 / 0 09:14:23 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388000256; Write bytes: 382269440; Read time: 28680; Write time: 641 09:14:23 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 8192; Write bytes: 299590144; Read time: 1; Write time: 1130 09:14:23 INFO - Running post-run listener: _upload_blobber_files 09:14:23 INFO - Blob upload gear active. 09:14:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:14:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:24 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:25 INFO - (blobuploader) - INFO - Done attempting. 09:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:26 INFO - (blobuploader) - INFO - Done attempting. 09:14:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:26 INFO - Return code: 0 09:14:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83ce788497cee3c0592381ffb179180d7eb708ff8106e91840c8c24a18d545b20b1dc21603951a59195a6c55f024d313d1ca6bdc630383b47f60c279950a868d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b954657717f994a5d76334d62475cb2dedc6ae53e96ddad6709f13fc65e7e13e45e2341f83831584d61859de00cee54c09d4fdf160dd455636e2abf48345a7e0"} 09:14:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:26 INFO - Contents: 09:14:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83ce788497cee3c0592381ffb179180d7eb708ff8106e91840c8c24a18d545b20b1dc21603951a59195a6c55f024d313d1ca6bdc630383b47f60c279950a868d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b954657717f994a5d76334d62475cb2dedc6ae53e96ddad6709f13fc65e7e13e45e2341f83831584d61859de00cee54c09d4fdf160dd455636e2abf48345a7e0"} 09:14:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:26 INFO - Copying logs to upload dir... 09:14:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.829616 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-12 09:14:26.154030) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 09:14:26.157643) ========= 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.SGD19xcY83/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.kGA3nK2S9N/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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/83ce788497cee3c0592381ffb179180d7eb708ff8106e91840c8c24a18d545b20b1dc21603951a59195a6c55f024d313d1ca6bdc630383b47f60c279950a868d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b954657717f994a5d76334d62475cb2dedc6ae53e96ddad6709f13fc65e7e13e45e2341f83831584d61859de00cee54c09d4fdf160dd455636e2abf48345a7e0"} build_url:https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/InOErNaoROyc7H_QE09d1Q/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/83ce788497cee3c0592381ffb179180d7eb708ff8106e91840c8c24a18d545b20b1dc21603951a59195a6c55f024d313d1ca6bdc630383b47f60c279950a868d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b954657717f994a5d76334d62475cb2dedc6ae53e96ddad6709f13fc65e7e13e45e2341f83831584d61859de00cee54c09d4fdf160dd455636e2abf48345a7e0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 09:14:26.186688) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:14:26.187169) ========= 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.SGD19xcY83/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.kGA3nK2S9N/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.005156 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 09:14:26.236589) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 09:14:26.237127) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 09:14:26.237596) ========= ========= Total master_lag: 0.17 =========