builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0375 starttime: 1463683551.18 results: success (0) buildid: 20160519094747 builduid: 3add65188c5d44a6aefafbc796a7a97c revision: 1806d405c8715949b39fa3a4fc142d14a60df590 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.178315) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.178791) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.179071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.202078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.202467) ========= 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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-19 11:45:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-05-19 11:45:51 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121309 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.342319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.342646) ========= 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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.020786 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:51.390924) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-19 11:45:51.391252) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1806d405c8715949b39fa3a4fc142d14a60df590 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1806d405c8715949b39fa3a4fc142d14a60df590 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IfOgzo5zGQ/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.N1LLP49ca1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-19 11:45:51,473 truncating revision to first 12 chars 2016-05-19 11:45:51,474 Setting DEBUG logging. 2016-05-19 11:45:51,474 attempt 1/10 2016-05-19 11:45:51,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1806d405c871?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 11:45:53,206 unpacking tar archive at: mozilla-central-1806d405c871/testing/mozharness/ program finished with exit code 0 elapsedTime=1.986847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-19 11:45:53.398679) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:53.399016) ========= script_repo_revision: 1806d405c8715949b39fa3a4fc142d14a60df590 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:53.399635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:53.399905) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 11:45:53.414372) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 7 secs) (at 2016-05-19 11:45:53.414654) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IfOgzo5zGQ/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.N1LLP49ca1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:45:53 INFO - MultiFileLogger online at 20160519 11:45:53 in /builds/slave/test 11:45:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:45:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:45:53 INFO - {'append_to_log': False, 11:45:53 INFO - 'base_work_dir': '/builds/slave/test', 11:45:53 INFO - 'blob_upload_branch': 'Firefox', 11:45:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:45:53 INFO - 'branch': 'Firefox', 11:45:53 INFO - 'buildbot_json_path': 'buildprops.json', 11:45:53 INFO - 'config_files': ('talos/mac_config.py',), 11:45:53 INFO - 'default_actions': ('clobber', 11:45:53 INFO - 'read-buildbot-config', 11:45:53 INFO - 'download-and-extract', 11:45:53 INFO - 'populate-webroot', 11:45:53 INFO - 'create-virtualenv', 11:45:53 INFO - 'install', 11:45:53 INFO - 'run-tests'), 11:45:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:45:53 INFO - 'download_minidump_stackwalk': True, 11:45:53 INFO - 'download_symbols': 'ondemand', 11:45:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:45:53 INFO - 'tooltool.py': '/tools/tooltool.py', 11:45:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:45:53 INFO - '/tools/misc-python/virtualenv.py')}, 11:45:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:45:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:45:53 INFO - 'installer_path': 'installer.exe', 11:45:53 INFO - 'log_level': 'info', 11:45:53 INFO - 'log_name': 'talos', 11:45:53 INFO - 'log_to_console': True, 11:45:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:45:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:45:53 INFO - 'opt_config_files': (), 11:45:53 INFO - 'pip_index': False, 11:45:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:53 INFO - 'cmd': ('bash', 11:45:53 INFO - '-c', 11:45:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:53 INFO - 'enabled': True, 11:45:53 INFO - 'halt_on_failure': False, 11:45:53 INFO - 'name': 'check_screen_resolution'},), 11:45:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:53 INFO - 'cmd': ('bash', 11:45:53 INFO - '-c', 11:45:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:53 INFO - 'enabled': True, 11:45:53 INFO - 'halt_on_failure': False, 11:45:53 INFO - 'name': 'check_screen_resolution'},), 11:45:53 INFO - 'run_cmd_checks_enabled': True, 11:45:53 INFO - 'sps_profile': False, 11:45:53 INFO - 'sps_profile_interval': 0, 11:45:53 INFO - 'suite': 'dromaeojs-e10s', 11:45:53 INFO - 'system_bits': '32', 11:45:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:45:53 INFO - 'title': 't-yosemite-r7-0375', 11:45:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:45:53 INFO - 'use_talos_json': True, 11:45:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:45:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:45:53 INFO - 'work_dir': 'build'} 11:45:53 INFO - ##### 11:45:53 INFO - ##### Running clobber step. 11:45:53 INFO - ##### 11:45:53 INFO - Running pre-action listener: _resource_record_pre_action 11:45:53 INFO - Running main action method: clobber 11:45:53 INFO - rmtree: /builds/slave/test/build 11:45:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:45:54 INFO - Running post-action listener: _resource_record_post_action 11:45:54 INFO - ##### 11:45:54 INFO - ##### Finished clobber step (success) 11:45:54 INFO - ##### 11:45:54 INFO - ##### 11:45:54 INFO - ##### Running read-buildbot-config step. 11:45:54 INFO - ##### 11:45:54 INFO - Running pre-action listener: _resource_record_pre_action 11:45:54 INFO - Running main action method: read_buildbot_config 11:45:54 INFO - Using buildbot properties: 11:45:54 INFO - { 11:45:54 INFO - "project": "", 11:45:54 INFO - "product": "firefox", 11:45:54 INFO - "script_repo_revision": "production", 11:45:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:45:54 INFO - "repository": "", 11:45:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:45:54 INFO - "buildid": "20160519094747", 11:45:54 INFO - "pgo_build": "False", 11:45:54 INFO - "basedir": "/builds/slave/test", 11:45:54 INFO - "buildnumber": 281, 11:45:54 INFO - "slavename": "t-yosemite-r7-0375", 11:45:54 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:45:54 INFO - "platform": "yosemite_r7", 11:45:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:45:54 INFO - "branch": "mozilla-central", 11:45:54 INFO - "repo_path": "mozilla-central", 11:45:54 INFO - "revision": "1806d405c8715949b39fa3a4fc142d14a60df590", 11:45:54 INFO - "stage_platform": "macosx64", 11:45:54 INFO - "builduid": "3add65188c5d44a6aefafbc796a7a97c", 11:45:54 INFO - "slavebuilddir": "test" 11:45:54 INFO - } 11:45:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:45:54 INFO - Running post-action listener: _resource_record_post_action 11:45:54 INFO - ##### 11:45:54 INFO - ##### Finished read-buildbot-config step (success) 11:45:54 INFO - ##### 11:45:54 INFO - ##### 11:45:54 INFO - ##### Running download-and-extract step. 11:45:54 INFO - ##### 11:45:54 INFO - Running pre-action listener: _resource_record_pre_action 11:45:54 INFO - Running main action method: download_and_extract 11:45:54 INFO - mkdir: /builds/slave/test/build/tests 11:45: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')]} 11:45:54 INFO - https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:45:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:45:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:45:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:45:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:45:57 INFO - Downloaded 1199 bytes. 11:45:57 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:45:57 INFO - Using the following test package requirements: 11:45:57 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:45:57 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:45:57 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:45:57 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'jsshell-mac.zip'], 11:45:57 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:45:57 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:45:57 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:45:57 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:45:57 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:45:57 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:45:57 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:45:57 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:45:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:45:57 INFO - https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:45:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:45:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:45:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:45:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:46:06 INFO - Downloaded 18630474 bytes. 11:46:06 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'] 11:46:06 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 11:46:09 INFO - Return code: 0 11:46:09 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 11:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:46:09 INFO - https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:46:15 INFO - Downloaded 18630474 bytes. 11:46:15 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'] 11:46:15 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 11:46:18 INFO - Return code: 0 11:46:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:46:18 INFO - https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:46:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:46:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:46:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:46:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:46:19 INFO - Downloaded 10840359 bytes. 11:46:19 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'] 11:46:19 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 11:46:19 INFO - Return code: 0 11:46:19 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')]} 11:46:19 INFO - https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:46:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:46:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:46:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:46:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GBImwxvlRdyfwPXyxkvazA/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 11:50:06 INFO - Downloaded 110161304 bytes. 11:50:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:50:06 INFO - mkdir: /builds/slave/test/properties 11:50:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:50:06 INFO - Writing to file /builds/slave/test/properties/build_url 11:50:06 INFO - Contents: 11:50:06 INFO - build_url:https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:50:08 INFO - Running post-action listener: _resource_record_post_action 11:50:08 INFO - Running post-action listener: set_extra_try_arguments 11:50:08 INFO - ##### 11:50:08 INFO - ##### Finished download-and-extract step (success) 11:50:08 INFO - ##### 11:50:08 INFO - ##### 11:50:08 INFO - ##### Running populate-webroot step. 11:50:08 INFO - ##### 11:50:08 INFO - Running pre-action listener: _resource_record_pre_action 11:50:08 INFO - Running main action method: populate_webroot 11:50:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:50:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:50:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:50:08 INFO - '--tppagecycles', 11:50:08 INFO - '7'], 11:50:08 INFO - 'tests': ['tsvgm']}}, 11:50:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:50:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:50:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:50:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:50:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:50:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['damp', 'tps']}, 11:50:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'tests': ['damp', 'tps']}, 11:50:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['dromaeo_dom']}, 11:50:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:50:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['a11yr', 11:50:08 INFO - 'ts_paint', 11:50:08 INFO - 'tpaint', 11:50:08 INFO - 'sessionrestore', 11:50:08 INFO - 'sessionrestore_no_auto_restore', 11:50:08 INFO - 'tabpaint']}, 11:50:08 INFO - 'other-e10s': {'tests': ['a11yr', 11:50:08 INFO - 'ts_paint', 11:50:08 INFO - 'tpaint', 11:50:08 INFO - 'sessionrestore', 11:50:08 INFO - 'sessionrestore_no_auto_restore', 11:50:08 INFO - 'tabpaint']}, 11:50:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:50:08 INFO - 'ts_paint', 11:50:08 INFO - 'tpaint', 11:50:08 INFO - 'sessionrestore', 11:50:08 INFO - 'sessionrestore_no_auto_restore', 11:50:08 INFO - 'tabpaint']}, 11:50:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:50:08 INFO - 'ts_paint', 11:50:08 INFO - 'tpaint', 11:50:08 INFO - 'sessionrestore', 11:50:08 INFO - 'sessionrestore_no_auto_restore', 11:50:08 INFO - 'tabpaint']}, 11:50:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['a11yr', 11:50:08 INFO - 'ts_paint', 11:50:08 INFO - 'tpaint', 11:50:08 INFO - 'sessionrestore', 11:50:08 INFO - 'sessionrestore_no_auto_restore', 11:50:08 INFO - 'tabpaint']}, 11:50:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['a11yr', 11:50:08 INFO - 'ts_paint', 11:50:08 INFO - 'tpaint', 11:50:08 INFO - 'sessionrestore', 11:50:08 INFO - 'sessionrestore_no_auto_restore', 11:50:08 INFO - 'tabpaint']}, 11:50:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['tsvgx', 11:50:08 INFO - 'tsvgr_opacity', 11:50:08 INFO - 'tart', 11:50:08 INFO - 'tscrollx', 11:50:08 INFO - 'cart']}, 11:50:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:50:08 INFO - 'tsvgr_opacity', 11:50:08 INFO - 'tart', 11:50:08 INFO - 'tscrollx', 11:50:08 INFO - 'cart']}, 11:50:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'talos_options': ['--disable-e10s'], 11:50:08 INFO - 'tests': ['tp5o']}, 11:50:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'tests': ['tp5o']}, 11:50:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'talos_options': ['--disable-e10s', 11:50:08 INFO - '--xperf_path', 11:50:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:08 INFO - 'tests': ['tp5n']}, 11:50:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:50:08 INFO - 'talos_options': ['--xperf_path', 11:50:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:50:08 INFO - 'tests': ['tp5n']}}, 11:50:08 INFO - 'talos.zip': {'path': '', 11:50:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:50:08 INFO - Running post-action listener: _resource_record_post_action 11:50:08 INFO - ##### 11:50:08 INFO - ##### Finished populate-webroot step (success) 11:50:08 INFO - ##### 11:50:08 INFO - ##### 11:50:08 INFO - ##### Running create-virtualenv step. 11:50:08 INFO - ##### 11:50:08 INFO - Running pre-action listener: _resource_record_pre_action 11:50:08 INFO - Running main action method: create_virtualenv 11:50:08 INFO - Creating virtualenv /builds/slave/test/build/venv 11:50:08 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 11:50:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:50:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:50:08 INFO - Using real prefix '/tools/python27' 11:50:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:50:08 INFO - Installing distribute.............................................................................................................................................................................................done. 11:50:11 INFO - Installing pip.................done. 11:50:11 INFO - Return code: 0 11:50:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:11 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 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:11 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 11:50:11 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 11:50:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:11 INFO - 'HOME': '/Users/cltbld', 11:50:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:11 INFO - 'LOGNAME': 'cltbld', 11:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:11 INFO - 'MOZ_NO_REMOTE': '1', 11:50:11 INFO - 'NO_EM_RESTART': '1', 11:50:11 INFO - 'PAGER': '/bin/cat', 11:50:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:11 INFO - 'PWD': '/builds/slave/test', 11:50:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:11 INFO - 'SHELL': '/bin/bash', 11:50:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:11 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:11 INFO - 'USER': 'cltbld', 11:50:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:11 INFO - 'XPC_FLAGS': '0x0', 11:50:11 INFO - 'XPC_SERVICE_NAME': '0', 11:50:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:12 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 11:50:12 INFO - Cleaning up... 11:50:12 INFO - Return code: 0 11:50:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:50:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:50:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:12 INFO - 'HOME': '/Users/cltbld', 11:50:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:12 INFO - 'LOGNAME': 'cltbld', 11:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:12 INFO - 'MOZ_NO_REMOTE': '1', 11:50:12 INFO - 'NO_EM_RESTART': '1', 11:50:12 INFO - 'PAGER': '/bin/cat', 11:50:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:12 INFO - 'PWD': '/builds/slave/test', 11:50:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:12 INFO - 'SHELL': '/bin/bash', 11:50:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:12 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:12 INFO - 'USER': 'cltbld', 11:50:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:12 INFO - 'XPC_FLAGS': '0x0', 11:50:12 INFO - 'XPC_SERVICE_NAME': '0', 11:50:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:12 INFO - Downloading/unpacking psutil>=3.1.1 11:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:50:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:50:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:50:15 INFO - Installing collected packages: psutil 11:50:15 INFO - Running setup.py install for psutil 11:50:16 INFO - building 'psutil._psutil_osx' extension 11:50:16 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 11:50:16 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 11:50:16 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 11:50:16 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 11:50:16 INFO - building 'psutil._psutil_posix' extension 11:50:16 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 11:50:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:50:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:50:16 INFO - ^ 11:50:16 INFO - 1 warning generated. 11:50:16 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 11:50:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:50:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:50:16 INFO - Successfully installed psutil 11:50:16 INFO - Cleaning up... 11:50:16 INFO - Return code: 0 11:50:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:50:16 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')]} 11:50:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:16 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')]} 11:50:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:16 INFO - 'HOME': '/Users/cltbld', 11:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:16 INFO - 'LOGNAME': 'cltbld', 11:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:16 INFO - 'MOZ_NO_REMOTE': '1', 11:50:16 INFO - 'NO_EM_RESTART': '1', 11:50:16 INFO - 'PAGER': '/bin/cat', 11:50:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:16 INFO - 'PWD': '/builds/slave/test', 11:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:16 INFO - 'SHELL': '/bin/bash', 11:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:16 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:16 INFO - 'USER': 'cltbld', 11:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:16 INFO - 'XPC_FLAGS': '0x0', 11:50:16 INFO - 'XPC_SERVICE_NAME': '0', 11:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:50:19 INFO - Installing collected packages: mozsystemmonitor 11:50:19 INFO - Running setup.py install for mozsystemmonitor 11:50:19 INFO - Successfully installed mozsystemmonitor 11:50:19 INFO - Cleaning up... 11:50:19 INFO - Return code: 0 11:50:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:50:19 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')]} 11:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:19 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')]} 11:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:19 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 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:19 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 11:50:19 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 11:50:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:19 INFO - 'HOME': '/Users/cltbld', 11:50:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:19 INFO - 'LOGNAME': 'cltbld', 11:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:19 INFO - 'MOZ_NO_REMOTE': '1', 11:50:19 INFO - 'NO_EM_RESTART': '1', 11:50:19 INFO - 'PAGER': '/bin/cat', 11:50:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:19 INFO - 'PWD': '/builds/slave/test', 11:50:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:19 INFO - 'SHELL': '/bin/bash', 11:50:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:19 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:19 INFO - 'USER': 'cltbld', 11:50:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:19 INFO - 'XPC_FLAGS': '0x0', 11:50:19 INFO - 'XPC_SERVICE_NAME': '0', 11:50:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:20 INFO - Downloading/unpacking blobuploader==1.2.4 11:50:20 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 11:50:20 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 11:50:20 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 11:50:20 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 11:50:20 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 11:50:20 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 11:50:22 INFO - Downloading blobuploader-1.2.4.tar.gz 11:50:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:50:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:50:22 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 11:50:22 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 11:50:22 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 11:50:22 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 11:50:22 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 11:50:22 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 11:50:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:50:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:23 INFO - Downloading docopt-0.6.1.tar.gz 11:50:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:50:23 INFO - Installing collected packages: blobuploader, requests, docopt 11:50:23 INFO - Running setup.py install for blobuploader 11:50:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:50:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:50:24 INFO - Running setup.py install for requests 11:50:24 INFO - Running setup.py install for docopt 11:50:24 INFO - Successfully installed blobuploader requests docopt 11:50:24 INFO - Cleaning up... 11:50:24 INFO - Return code: 0 11:50:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50: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')]} 11:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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')]} 11:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:24 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 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:24 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 11:50:24 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 11:50:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:24 INFO - 'HOME': '/Users/cltbld', 11:50:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:24 INFO - 'LOGNAME': 'cltbld', 11:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:24 INFO - 'MOZ_NO_REMOTE': '1', 11:50:24 INFO - 'NO_EM_RESTART': '1', 11:50:24 INFO - 'PAGER': '/bin/cat', 11:50:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:24 INFO - 'PWD': '/builds/slave/test', 11:50:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:24 INFO - 'SHELL': '/bin/bash', 11:50:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:24 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:24 INFO - 'USER': 'cltbld', 11:50:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:24 INFO - 'XPC_FLAGS': '0x0', 11:50:24 INFO - 'XPC_SERVICE_NAME': '0', 11:50:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-vz6bDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-V2oOGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ZXTn5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-98m21I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-SoLSiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-43Izoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-LnKAwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-EnZh8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-5mEGCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:50:25 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-okG2W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XPRapz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-CaUx52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-RJtFrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-1RjDsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-1s3x9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-fcJJOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:50:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:50:26 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-U9NLv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:50:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:50:26 INFO - Running setup.py install for manifestparser 11:50:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:50:26 INFO - Running setup.py install for mozcrash 11:50:27 INFO - Running setup.py install for mozdebug 11:50:27 INFO - Running setup.py install for mozdevice 11:50:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Running setup.py install for mozfile 11:50:27 INFO - Running setup.py install for mozhttpd 11:50:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Running setup.py install for mozinfo 11:50:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Running setup.py install for mozInstall 11:50:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:50:27 INFO - Running setup.py install for mozleak 11:50:28 INFO - Running setup.py install for mozlog 11:50:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:50:28 INFO - Running setup.py install for moznetwork 11:50:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:50:28 INFO - Running setup.py install for mozprocess 11:50:28 INFO - Running setup.py install for mozprofile 11:50:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:50:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:50:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:50:28 INFO - Running setup.py install for mozrunner 11:50:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:50:28 INFO - Running setup.py install for mozscreenshot 11:50:29 INFO - Running setup.py install for moztest 11:50:29 INFO - Running setup.py install for mozversion 11:50:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:50:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:50:29 INFO - Cleaning up... 11:50:29 INFO - Return code: 0 11:50:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50: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')]} 11:50:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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')]} 11:50:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:29 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 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:29 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 11:50:29 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 11:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:29 INFO - 'HOME': '/Users/cltbld', 11:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:29 INFO - 'LOGNAME': 'cltbld', 11:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:29 INFO - 'MOZ_NO_REMOTE': '1', 11:50:29 INFO - 'NO_EM_RESTART': '1', 11:50:29 INFO - 'PAGER': '/bin/cat', 11:50:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:29 INFO - 'PWD': '/builds/slave/test', 11:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:29 INFO - 'SHELL': '/bin/bash', 11:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:29 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:29 INFO - 'USER': 'cltbld', 11:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:29 INFO - 'XPC_FLAGS': '0x0', 11:50:29 INFO - 'XPC_SERVICE_NAME': '0', 11:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:50:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-R7NzM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:50:29 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)) 11:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:50:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-cqPsZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:50:29 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)) 11:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:50:29 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Su3n6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:50:29 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)) 11:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_rbXDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-hjO1RZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-C3XivE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-7i0qad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-590eUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-We6HuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-HdWaGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-U2mTmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-DMyQM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:50:30 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)) 11:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:50:30 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-C9LrEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:50:31 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)) 11:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:50:31 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-HYGIP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:50:31 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)) 11:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:31 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-mF1blb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:50:31 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)) 11:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:50:31 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-rPoyjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:50:31 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)) 11:50:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:50:31 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-FlRuTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:50:31 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)) 11:50:31 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)) 11:50:31 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)) 11:50:31 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)) 11:50:31 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)) 11:50:31 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)) 11:50:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:50:31 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 11:50:31 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 11:50:31 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 11:50:31 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 11:50:31 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 11:50:31 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 11:50:33 INFO - Downloading blessings-1.6.tar.gz 11:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:50:33 INFO - Installing collected packages: blessings 11:50:33 INFO - Running setup.py install for blessings 11:50:34 INFO - Successfully installed blessings 11:50:34 INFO - Cleaning up... 11:50:34 INFO - Return code: 0 11:50:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:50:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:50:34 INFO - Reading from file tmpfile_stdout 11:50:34 INFO - Current package versions: 11:50:34 INFO - blessings == 1.6 11:50:34 INFO - blobuploader == 1.2.4 11:50:34 INFO - docopt == 0.6.1 11:50:34 INFO - manifestparser == 1.1 11:50:34 INFO - mozInstall == 1.12 11:50:34 INFO - mozcrash == 0.17 11:50:34 INFO - mozdebug == 0.1 11:50:34 INFO - mozdevice == 0.48 11:50:34 INFO - mozfile == 1.2 11:50:34 INFO - mozhttpd == 0.7 11:50:34 INFO - mozinfo == 0.9 11:50:34 INFO - mozleak == 0.1 11:50:34 INFO - mozlog == 3.2 11:50:34 INFO - moznetwork == 0.27 11:50:34 INFO - mozprocess == 0.22 11:50:34 INFO - mozprofile == 0.28 11:50:34 INFO - mozrunner == 6.11 11:50:34 INFO - mozscreenshot == 0.1 11:50:34 INFO - mozsystemmonitor == 0.3 11:50:34 INFO - moztest == 0.7 11:50:34 INFO - mozversion == 1.4 11:50:34 INFO - psutil == 3.1.1 11:50:34 INFO - requests == 1.2.3 11:50:34 INFO - wsgiref == 0.1.2 11:50:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:50: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')]} 11:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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')]} 11:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:34 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 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:34 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 11:50:34 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 11:50:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:34 INFO - 'HOME': '/Users/cltbld', 11:50:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:34 INFO - 'LOGNAME': 'cltbld', 11:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:34 INFO - 'MOZ_NO_REMOTE': '1', 11:50:34 INFO - 'NO_EM_RESTART': '1', 11:50:34 INFO - 'PAGER': '/bin/cat', 11:50:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:34 INFO - 'PWD': '/builds/slave/test', 11:50:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:34 INFO - 'SHELL': '/bin/bash', 11:50:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:34 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:34 INFO - 'USER': 'cltbld', 11:50:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:34 INFO - 'XPC_FLAGS': '0x0', 11:50:34 INFO - 'XPC_SERVICE_NAME': '0', 11:50:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 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)) 11:50:34 INFO - Cleaning up... 11:50:34 INFO - Return code: 0 11:50:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:50: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')]} 11:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50: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')]} 11:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:50:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:34 INFO - 'HOME': '/Users/cltbld', 11:50:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:34 INFO - 'LOGNAME': 'cltbld', 11:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:34 INFO - 'MOZ_NO_REMOTE': '1', 11:50:34 INFO - 'NO_EM_RESTART': '1', 11:50:34 INFO - 'PAGER': '/bin/cat', 11:50:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:34 INFO - 'PWD': '/builds/slave/test', 11:50:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:34 INFO - 'SHELL': '/bin/bash', 11:50:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:34 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:34 INFO - 'USER': 'cltbld', 11:50:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:34 INFO - 'XPC_FLAGS': '0x0', 11:50:34 INFO - 'XPC_SERVICE_NAME': '0', 11:50:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:35 INFO - Downloading/unpacking jsonschema 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 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 11:50:35 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 11:50:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:50:37 INFO - Downloading/unpacking functools32 (from jsonschema) 11:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:37 INFO - Downloading functools32-3.2.3-2.tar.gz 11:50:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:50:37 INFO - warning: no files found matching '*.txt' 11:50:37 INFO - no previously-included directories found matching 'build' 11:50:37 INFO - no previously-included directories found matching 'dist' 11:50:37 INFO - no previously-included directories found matching '.git*' 11:50:37 INFO - Installing collected packages: jsonschema, functools32 11:50:38 INFO - Running setup.py install for functools32 11:50:38 INFO - warning: no files found matching '*.txt' 11:50:38 INFO - no previously-included directories found matching 'build' 11:50:38 INFO - no previously-included directories found matching 'dist' 11:50:38 INFO - no previously-included directories found matching '.git*' 11:50:38 INFO - Successfully installed jsonschema functools32 11:50:38 INFO - Cleaning up... 11:50:38 INFO - Return code: 0 11:50:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:50:38 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')]} 11:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:38 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')]} 11:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:50:38 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 0x10fb2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7105735e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, '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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 11:50:38 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 11:50:38 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 11:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:38 INFO - 'HOME': '/Users/cltbld', 11:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:38 INFO - 'LOGNAME': 'cltbld', 11:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:38 INFO - 'MOZ_NO_REMOTE': '1', 11:50:38 INFO - 'NO_EM_RESTART': '1', 11:50:38 INFO - 'PAGER': '/bin/cat', 11:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:38 INFO - 'PWD': '/builds/slave/test', 11:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:38 INFO - 'SHELL': '/bin/bash', 11:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:50:38 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:38 INFO - 'USER': 'cltbld', 11:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:38 INFO - 'XPC_FLAGS': '0x0', 11:50:38 INFO - 'XPC_SERVICE_NAME': '0', 11:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:50:38 INFO - Downloading/unpacking flake8 11:50:38 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 11:50:38 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 11:50:38 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 11:50:38 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 11:50:38 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 11:50:38 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 11:50:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:50:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:50:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:50:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:50:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:50:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:50:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:50:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:50:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:50:42 INFO - no previously-included directories found matching 'docs/_build' 11:50:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:50:42 INFO - Running setup.py install for pep8 11:50:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:50:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:50:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:50:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:50:42 INFO - no previously-included directories found matching 'docs/_build' 11:50:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:50:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:50:42 INFO - Cleaning up... 11:50:42 INFO - Return code: 0 11:50:42 INFO - Running post-action listener: _resource_record_post_action 11:50:42 INFO - Running post-action listener: _start_resource_monitoring 11:50:42 INFO - Starting resource monitoring. 11:50:42 INFO - ##### 11:50:42 INFO - ##### Finished create-virtualenv step (success) 11:50:42 INFO - ##### 11:50:42 INFO - ##### 11:50:42 INFO - ##### Running install step. 11:50:42 INFO - ##### 11:50:42 INFO - Running pre-action listener: _resource_record_pre_action 11:50:42 INFO - Running main action method: install 11:50:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:50:43 INFO - Reading from file tmpfile_stdout 11:50:43 INFO - Detecting whether we're running mozinstall >=1.0... 11:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:50:43 INFO - Reading from file tmpfile_stdout 11:50:43 INFO - Output received: 11:50:43 INFO - Usage: mozinstall [options] installer 11:50:43 INFO - Options: 11:50:43 INFO - -h, --help show this help message and exit 11:50:43 INFO - -d DEST, --destination=DEST 11:50:43 INFO - Directory to install application into. [default: 11:50:43 INFO - "/builds/slave/test"] 11:50:43 INFO - --app=APP Application being installed. [default: firefox] 11:50:43 INFO - mkdir: /builds/slave/test/build/application 11:50:43 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'] 11:50:43 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 11:51:16 INFO - Reading from file tmpfile_stdout 11:51:16 INFO - Output received: 11:51:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:51:16 INFO - Running post-action listener: _resource_record_post_action 11:51:16 INFO - ##### 11:51:16 INFO - ##### Finished install step (success) 11:51:16 INFO - ##### 11:51:16 INFO - ##### 11:51:16 INFO - ##### Running run-tests step. 11:51:16 INFO - ##### 11:51:16 INFO - Running pre-action listener: _resource_record_pre_action 11:51:16 INFO - Running main action method: run_tests 11:51:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:51:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:51:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:51:16 INFO - 2016-05-19 11:51:16.597 screenresolution[2543:35390] starting screenresolution argv=screenresolution get 11:51:16 INFO - 2016-05-19 11:51:16.600 screenresolution[2543:35390] Display 0: 1600x1200x32@60 11:51:16 INFO - 2016-05-19 11:51:16.606 screenresolution[2544:35392] starting screenresolution argv=screenresolution list 11:51:16 INFO - Available Modes on Display 0 11:51:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:51:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:51:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:51:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:51:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:51:16 INFO - Intel Iris: 11:51:16 INFO - Chipset Model: Intel Iris 11:51:16 INFO - Type: GPU 11:51:16 INFO - Bus: Built-In 11:51:16 INFO - VRAM (Dynamic, Max): 1536 MB 11:51:16 INFO - Vendor: Intel (0x8086) 11:51:16 INFO - Device ID: 0x0a2e 11:51:16 INFO - Revision ID: 0x0009 11:51:16 INFO - Displays: 11:51:16 INFO - Display: 11:51:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:51:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:51:16 INFO - Display Serial Number: 1600x1200 60 11:51:16 INFO - Main Display: Yes 11:51:16 INFO - Mirror: Off 11:51:16 INFO - Online: Yes 11:51:16 INFO - Rotation: Supported 11:51:16 INFO - Return code: 0 11:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:51:16 INFO - Python 2.7.3 11:51:16 INFO - Return code: 0 11:51:16 INFO - grabbing minidump binary from tooltool 11:51:16 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')]} 11:51:16 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 0x10f9ced30>, '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 0x10f7f2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9c5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:51:16 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 11:51:16 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 11:51:16 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 11:51:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:51:16 INFO - Return code: 0 11:51:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:51:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:51:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:51:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:51:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:51:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:51:18 INFO - Return code: 0 11:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 11:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 11:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IfOgzo5zGQ/Render', 11:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:51:18 INFO - 'HOME': '/Users/cltbld', 11:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:51:18 INFO - 'LOGNAME': 'cltbld', 11:51:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:51:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:51:18 INFO - 'MOZ_NO_REMOTE': '1', 11:51:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:51:18 INFO - 'NO_EM_RESTART': '1', 11:51:18 INFO - 'PAGER': '/bin/cat', 11:51:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:51:18 INFO - 'PWD': '/builds/slave/test', 11:51:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:51:18 INFO - 'SHELL': '/bin/bash', 11:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.N1LLP49ca1/Listeners', 11:51:18 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 11:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:51:18 INFO - 'USER': 'cltbld', 11:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:51:18 INFO - 'XPC_FLAGS': '0x0', 11:51:18 INFO - 'XPC_SERVICE_NAME': '0', 11:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:51:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 11:51:18 INFO - mozversion application_buildid: 20160519094747 11:51:18 INFO - mozversion application_changeset: 1806d405c8715949b39fa3a4fc142d14a60df590 11:51:18 INFO - mozversion application_display_name: Nightly 11:51:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:51:18 INFO - mozversion application_name: Firefox 11:51:18 INFO - mozversion application_remotingname: firefox 11:51:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:51:18 INFO - mozversion application_vendor: Mozilla 11:51:18 INFO - mozversion application_version: 49.0a1 11:51:18 INFO - mozversion platform_buildid: 20160519094747 11:51:18 INFO - mozversion platform_changeset: 1806d405c8715949b39fa3a4fc142d14a60df590 11:51:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:51:18 INFO - mozversion platform_version: 49.0a1 11:51:18 INFO - using testdate: 1463683878 11:51:18 INFO - actual date: 1463683878 11:51:18 INFO - starting webserver on 'localhost:59612' 11:51:18 INFO - SUITE-START | Running 2 tests 11:51:18 INFO - TEST-START | dromaeo_css 11:51:18 INFO - operating with platform_type : mac_ 11:51:18 INFO - Initialising browser for dromaeo_css test... 11:51:18 INFO - TEST-INFO | started process 2562 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpC9CGh0/profile http://localhost:59612/getInfo.html) 11:51:21 INFO - PROCESS | 2562 | __metrics Screen width/height:1600/1200 11:51:21 INFO - PROCESS | 2562 | colorDepth:24 11:51:21 INFO - PROCESS | 2562 | Browser inner width/height: 1024/689 11:51:21 INFO - PROCESS | 2562 | __metrics 11:51:21 ERROR - PROCESS | 2562 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 11:51:21 INFO - TEST-INFO | 2562: exit 0 11:51:21 INFO - Browser initialized. 11:51:21 INFO - Running cycle 1/1 for dromaeo_css test... 11:51:21 INFO - TEST-INFO | started process 2565 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpC9CGh0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:51:34 INFO - PROCESS | 2565 | RSS: Main: 250019840 11:51:34 INFO - PROCESS | 2565 | 11:52:23 INFO - PROCESS | 2565 | 1463683943663 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] 11:54:34 INFO - PROCESS | 2565 | Cycle 1(1): loaded http://localhost:59612/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59612/tests/dromaeo/cssquery-ext.html) 11:54:36 INFO - PROCESS | 2565 | RSS: Main: 260743168 11:54:36 INFO - PROCESS | 2565 | 11:57:38 INFO - PROCESS | 2565 | Cycle 1(1): loaded http://localhost:59612/tests/dromaeo/cssquery-ext.html (next: http://localhost:59612/tests/dromaeo/cssquery-jquery.html) 11:57:38 INFO - PROCESS | 2565 | RSS: Main: 273104896 11:57:38 INFO - PROCESS | 2565 | 12:00:40 INFO - PROCESS | 2565 | Cycle 1(1): loaded http://localhost:59612/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59612/tests/dromaeo/cssquery-mootools.html) 12:00:41 INFO - PROCESS | 2565 | RSS: Main: 275529728 12:00:41 INFO - PROCESS | 2565 | 12:03:43 INFO - PROCESS | 2565 | Cycle 1(1): loaded http://localhost:59612/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59612/tests/dromaeo/cssquery-prototype.html) 12:03:43 INFO - PROCESS | 2565 | RSS: Main: 276942848 12:03:43 INFO - PROCESS | 2565 | 12:06:46 INFO - PROCESS | 2565 | Cycle 1(1): loaded http://localhost:59612/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59612/tests/dromaeo/cssquery-yui.html) 12:06:47 INFO - PROCESS | 2565 | RSS: Main: 280186880 12:06:47 INFO - PROCESS | 2565 | 12:09:49 INFO - PROCESS | 2565 | Cycle 1(1): loaded http://localhost:59612/tests/dromaeo/cssquery-yui.html (next: http://localhost:59612/tests/dromaeo/cssquery-dojo.html) 12:09:49 INFO - PROCESS | 2565 | RSS: Main: 281231360 12:09:49 INFO - PROCESS | 2565 | 12:09:49 INFO - PROCESS | 2565 | __start_tp_report 12:09:49 INFO - PROCESS | 2565 | _x_x_mozilla_page_load 12:09:49 INFO - PROCESS | 2565 | _x_x_mozilla_page_load_details 12:09:49 INFO - PROCESS | 2565 | |i|pagename|runs| 12:09:49 INFO - PROCESS | 2565 | |0;dojo.html;3507.6843084122424;3528.488369186467;3534.9646503534964;3539.646035396461;3548.6451354864503;5776.682282474468;5857;5863.9999999999945;5963.373845746189;6001;5726.484616384525;5887.735051922663;5927.081302398135;5964.880702385961;6037.3660765619525;5239.056969745444;5283.86790330241;5378.946210537903;5413.188021796723;5444.727763611823;307.8948746197266;5269.367675878881;5334.013207556595;5364.409914909357;5377.059014672436;5320.201969704536;5338.439463856317;5347.278117454132;5364.275822763916;5398;5352.892942141136;5389.191621256804;5412.431694672042;5428.592855535811;5437.592180586433;5221.947780522184;5237.921431178535;5311.468853114697;5323.760430780624;5388.5958553108285;5606.243157173811;5724.227229324071;5826.300843898759;5851.707414629294;5913.8817223655715;6177.845553861144;6219.440250377443;6296.527760417941;6300;6304.653244071575;6239.812805615838;6282.685865706742;6342.5243106767175;6393.1688880445245;6398.040293955897;5111.897762044739;6110.450059494667;6117.235345581802;6137.41694539018;6144.815655530297;6216.222972128484;6357.332480089524;6474.870502589922;6504.9674751625935;6556.836079097965;6156.138140660311;6196.318404975449;6206.844828879224;6209.037599172136;6211.596246243979;6307.779227727007;6358.7456501740335;6427.260865000585;6429.067785171124;6431.646259455775;5192.584593232532;6215.782447614402;6242;6268.373162683786;6313.779017734358;6238.500919926395;6254.781082662085;6292.1505596743855;6299.244090709144;6312.810615681537;5165.070287348238;5165.276861239429;5166.819161329336;5175.275461435402;5193.1171700810955;4495.752733599618;4511.458624965026;4520.9095818084115;4541.909161816811;4618.607418369476;6000.279966403972;6003.519718422603;6047.516198704093;6138.815835524942;6139.693015349215;6340.858347804515;6403.807885763435;6415.037744338293;6417.679116044273;6418.1656384669695;5887.587868849139;6032.155498230251;6077.483413909778;6095.634261944208;6105.389461053859;5558.277423934944;5576.414476479992;5594.916076258859;5602.887942241132;5603.495685388335;6320.715567799552;6591.307912669101;6605.339466053356;6606.372394622503;6635.635040072746;6261.123442717932;6331.398517140864;6334.1765570475545;6401.007843784221;6409.647469389229;6561.020581053433;6631.33686631333;6647;6653.301403352578;6656.334366563498;6142.897836592492;6215.316315205233;6319.368063193644;6334.778282760266;6335.366463353628;6237.220347456568;6318.62088274705;6356.459700925473;6386.457151142205;6390.201224846895;5580.972095139498;6208.875822483616;6280.560360774884;6300.243970723543;6305.842353941188;6118.862843290023;6199.473044791062;6209.006558950547;6293.55945083858;6318.936810631835;6128.417800308964;6184.783532576338;6202.883480973468;6230.719617617308;6232.376762323732;6229.50163986889;6232.220972378453;6242.625442473466;6256.436920677161;6263.655498947602;4936.950630493793;6128.228138398679;6145.1564530639735;6145.832291864532;6177.598456100339 12:09:49 INFO - PROCESS | 2565 | |1;ext.html;500.24712807225126;509.30225590940415;514.7799714676217;528.4292963599313;570.5207625594505;39160.825175244754;42058.369124463155;42075;42152.36771448431;42195;42553.787231063805;42755.78622106893;43253;43342.783286083606;43442.913927151836;9494.66768663097;9703.805923881519;9711.417314961089;9730.805383892319;9737.07497787709;9601.1358977692;9661.855072173888;9684.467354295512;9713.805723885518;9738.610455581804;8527.403081784247;9726.416415015121;9735.90264097357;9737.756556086119;9747.512624368788;8518.914810851875;9499.382540134939;9653.855192172123;9697.951510242474;9731.41611503312;9490.050994900525;9551.331406801526;9591.328606997513;9597.856032159454;9627.374220675634;40007.79996100001;40144.19711605781;40159.7992010041;40538;40562.39156412656;2790.55033929093;2866.985665071682;3071.032624723205;3201.5677883485664;3244.3186930744337;26854.413374465083;27850.3037424064;28502.572461413096;28838.711612883813;29242.853785730935;2654.9070782522713;2677.8928842846262;2680.745329193743;2788.9442211155865;2869.741723244897;467533;486503;493426;494846;494940.9999999964;34377.48433773495;34437;34533.30933381318;34628.48057279143;34635.307293853984;33807.492887606706;34440.622375104715;35913;36203.4569481458;36962.26075478475;12664.936675316565;14745.041572297767;15080.472183473526;15214.315355809014;15278.465253716067;12879.969602431784;13000.284984325954;13002.739945201043;13013.869861301266;13043.869561304455;6954.165500140016;7041.859162816715;7862.2923936845955;8075.636596353178;8108.83782324362;3310.536524886518;3366.6801653842845;3372.2295794707975;3372.662733726657;3394.983025084908;885.2829208341135;930.5533343994921;1006.6225165562914;1008.8234558952154;1041.4638408347778;23370.532589348117;29381;29538;30387.2403189922;30805.383892322476;1044.0059486381128;1045.9738506537396;1053.3311347294523;1063.4576366053368;1112.8887111288968;15251.313690883937;15298.005629634039;15320.310586023656;15571.454999074978;15595.064296142267;675608;692859;695495;696152;700586;61298;61774.76450470965;62019.069713954326;62073.06890396648;62140;1384.8892088632886;1411.4072089722342;1437.6837095838898;1447.2474313356995;1448.5943935698233;28972;29070;29281.85359073191;29615.555766663947;29709.5543566851;50671.23993140032;50803;50878;51637.7418112907;51721.7413912928;613769;635075;636860;637201;638859;26239.868800655873;26499.867500662374;26676.333091672863;26857.86571067132;26870.32824179333;45270;45697.31454028126;46041;46529;47752.5224747762;36196.45705314475;38604;38865.41701874418;38905.83282501529;39044;37954.051148721504;38521.61478385292;38583.61416385912;38900.80549597347;39263.60736392599;30426.695733042387;38051.61948380481;38250.617493824706;38750.224995500495;38842;34075.829620851735;34219;34513.30973380568;34610;34893.77871774475;24719.38201544976;24976.37559061038;25181.87409062943;25488.490230195664;25747.485050298514 12:09:49 INFO - PROCESS | 2565 | |2;jquery.html;385.6818125046837;392.88017154767795;393.742098925204;397.0451065188223;409.3409610527051;19524.707129393053;20172.69740953887;20944.9527523624;20990.685139722915;21059.789402105973;20042;20112;20278.493037674067;20392.87839168849;20686.793132068673;5468.343798744147;5770.596058275923;5774.942250577504;5783.5084017858535;5901.439363260483;4902.730349830758;5006.849794506171;5694.373618901917;5825.300963884319;5886.941130588682;5696.8860622787515;5797.101449275369;5803.95528804815;5807.680577568232;5820.505257053155;5724.055530837407;5733.942660573382;5787.305523337184;5795.420457954213;5812.593118481729;4883.975580122112;4999.550040496373;5029.1450453423;5031.949680503185;5041.218611115283;20266.79733202664;22483.550328993493;25300.873495632404;25858.482830343477;25898.482030359475;2138.9358319250446;2164.285785690718;2254.9098036078535;2288.39357570244;2638.2085374387607;9138.725838224798;9825.508724563742;9962.203023758082;10052.849207261825;10175.440350780704;1712.9636569875295;1739.843414092725;1812.0848971269556;1819.2631984046482;1877.7465042219262;102183;132312.0153197703;134379;134813.6518634811;136460;11415.885841141482;11492;11611.88388116125;11801.704957375963;11968.760624787456;14280.928595357165;14389.208593527457;14402.991790574559;14412.495562655466;14426.278686065654;8367.539785311745;8580.14198580137;8595.5702214889;8608.569571521399;8694.26098781598;8354.164583541597;8372.889577445321;8570.4000719949;8591.226789588967;8618.870716939251;4486.461624605068;4528.071745292207;4548.681592288508;4569.697636173741;4595.885102872455;1925.740025096594;1967.0558132096673;1972.3688419705843;1976.2687805512053;2007.498125468633;902.7697937026016;908.6092980018656;912.4844462878452;993.5479356892597;1039.77644806367;17793;19069.237230510902;20021.799782002287;20069.899650501946;20202.191912323637;801.6292140439797;889.8876404494382;991.9206463482905;1013.5692330759398;1051.7318083888713;10155.187584993182;10618.840717389245;10627.787444251071;10631.767328860871;10652.254342195973;621248;621918.8904055541;622940.7705922973;636512;636756.816215916;114557;132636.01045984318;139470;140753;144132;1009.9192064634811;1025.9538320775434;1043.0716662170673;1069.0325255643654;1074.5809134437575;17749.93500389981;17963.461096167135;18088;18260.178291977158;18438.801477903893;54220;55373.446265536826;55778.88442231214;56834;59673.70163149157;46955;68880.6555967217;75236.62381688057;75915;77935;6067.878642427038;7272.781816545512;7705.845883082419;7735.303822655988;8034.67861285542;10727.40999245049;12090.516379344728;12289.754204916031;12424.212403408037;12569.308688021882;10444.373337599769;10670.19973502006;10712.73218169552;10744.409057501913;10840.457977100956;10284.794304113726;10364.274500784873;10629.787404251718;10733.946330268298;10735.89264107349;10173.44046077473;10342.948285258526;10729.409882456539;10819.404932728776;10864.674059778219;6124.356942521149;7674.769756907302;7689.50018248812;7859.52842829432;7887.369010479262;8863.335249856416;9893.35693179941;10493.9475302623;10930.945345273542;10979.945100274448 12:09:49 INFO - PROCESS | 2565 | |3;mootools.html;1542.1364036139762;1584.413766906245;1798.937037203698;1885.406097079419;1923.1153669312116;6841.213260475047;8600.182982616649;8608.913910860889;9076.773080672978;9098.40860344078;8698.869516957251;8816.382853200279;8944.195022447979;8971.596278167483;8980.506072166028;2373.2049763329264;2390.7728765767224;2409.71083469983;2412.1316326122583;2413.553492603874;1934.5260411199276;2142.4965133193687;2198.0438509248447;2379.4170428245106;2391.390195500145;2355.646653002055;2383.5828729972186;2393.3059412770276;2398.244552965819;2402.159244264511;2371.2412028150998;2379.8453100548495;2381.095183830142;2387.9164229252065;2398.424378149249;2371.098982386682;2372.7745864143053;2386.4630458146953;2392.3062311929693;2397.29279862441;8967.103289671046;9007.594658240396;9022.909770902339;9052.456852588866;9054.094590540893;4311.82752689892;5358.98179345923;5454.372747134051;5470.17947307903;5483.232347471358;3364.713999310062;3492.982535087334;3521.3837578423672;3537.726418489342;3555.520004799371;1019.9133073688671;1042.155853758458;1042.5100202904623;1045.221310123955;1060.7878424315168;11740.943315101596;11746.941265293704;11858.051355891508;11865.991390731799;11943.641690749379;4535.659825513099;4556.453225612881;4633.9536604634195;4641.094986477605;4654.953450465452;9338.252939764803;9342.392744471723;9356.672516461891;9362.297827662816;9375.48434836077;5174.611904107207;5210.76551555181;5226.843194704165;5286.259923610698;5293.073712100367;4421.336799480039;4770.904581908412;4889.804407823718;4900.215965445512;4903.926441103386;3790.6019867914024;3835.0987517933263;3861.768293902375;3909.9218015639526;3961.267165574378;1167.095500986725;1178.2871362825538;1179.4633441783967;1188.1089183112665;1188.3285943441927;673.6093066021696;892.1702816380828;920.1120918313859;932.5570354081757;947.6920000999703;5596.412376700469;5693.943060569424;5728.198052272685;5902.291724992942;5978.432048955342;428.5371798457658;435.8910272431892;449.7885993583011;450.73181457033667;450.9616682582017;3874.4575759393706;3910.804459777;3979.422983667352;4004.799760012046;4014.8996275092773;10316.742081448025;10921.781564368668;10997.725056873642;10998.230123891411;11004.119670426348;10758.300710453796;10850.782984340269;10924.344539327665;10939;10958.506867190996;8284.875726864102;8394.076651568323;8404.20160084791;8453.408261421642;8472.618732157067;4845.224764037737;5811.418858114154;5820.126980952891;5830.970845145747;5926.170336152856;10054.29619926598;10153.984601539787;10256.48717564104;10312.742181445525;10354.17166626653;10007.749806254902;10215.744606384602;10489.947550262199;10521.78956420852;10557.20820938448;6544.4764418845425;6947.3747362737595;7125.786226413215;7141.714331426685;7243.637818108968;8472.3645726572;8498.617562209593;8525.104863989192;8597.26923211509;8610.569471526273;7392.519486233593;7498.700051997859;7566.35685966699;7642.885356719543;7703.422243331661;7406.629668516445;7461.4403919705155;7498.812529686801;7501;7593.278638529331;5909.674967876636;7082.893756593759;7099.609521476162;7138.857222855618;7147.142342918883;4738.85783426484;4841.176999909953;4877.6341774367775;4943.604511638989;5039.0929632666475;4902.068606964665;5021.279996802993;5033.622478314068;5079.949200507947;5182.8963420732125 12:09:49 INFO - PROCESS | 2565 | |4;prototype.html;293.3370582483587;304.83386554327876;318.26639595731837;323.2823132645526;325.77033191599924;4971.304017437557;5256.579473642109;5444.455554444554;5450.5367043801325;5756.992526307889;4521.638268938485;5581.1815033408075;5602.915956260649;5616.354119276284;5624.887502249952;3764.3036038332857;4260.3822445745345;4262.850800221985;4265.7014009019385;4273.252180868351;4136.772477513736;4205.873823785276;4242.666533406629;4266.317389217726;4268.551802060785;4149.211649786546;4171.728837625545;4173.1862286854075;4183.895402614944;4184.916301673965;4088.2232375848494;4187.194579631654;4199.580041995791;4199.664026877843;4267.829286828523;3240.789348692351;3654.433562797744;3677;3678.6505281998166;3686.5207523022104;4472.373867658531;5024.522670346311;5293.550048245904;5296.735163241861;5324.923279920834;3973.6290979611986;4295.591918767712;4619.514950930137;4849.466558678542;4887.144749668793;4681.46163191234;4898.534639209305;5819.912701309484;5838.2702162229725;5840.678762668052;3660.4143337066207;3945.822437990297;3965.940510892339;3993.7603743775467;3996.640302372771;4783.579740485535;5809.779946211301;6152.815415537541;6254.655993920333;6259.874802503924;3406.2457834215397;3727.496787933594;4020.778857162884;4025.8993525162105;4061.573534778806;3449.3618680544178;3842.51968503937;4076.184763047402;4080.571539988318;4087.305158123126;4095.365218391154;4313.61177494027;4381.890452738707;4745.7051368510865;4790.928136077961;4986.576141027981;5385.542228910508;5625.353084395347;5952.255968003999;5961.642301461839;1250.3123282194756;1291.2704322058007;1337;1343.9114317402934;1394.3237529798039;823.0822632764545;826.9539033122984;845.9383473740417;850.6639877248452;858.5492616376453;4134.911869505941;4431.8670439886855;4484.910301793946;4654.720716757005;4669.322948172496;4225.218334608107;4760;4776.94907120433;5087.46421237244;5110.66780659256;4025.5571887092397;4429.80065897029;4518.81924723014;4603.792829322689;4658.1848176568965;2414.6740190074465;2476.4056626409892;2524.482481091372;2534.9493010140063;2708.932276693059;4496.775161241925;4574.679772415899;4908.239222920453;5154.3814742230425;5401.7299135043095;4573.588377046081;4912;5149.819756308511;5164.347408829139;5223.641853118141;5687.800926967537;5881.41185881417;6364.490840732823;6426.678666066678;6439.710213040518;5245.632805703564;6144.631322120687;6158.753649853956;6165.383461653799;6195.349488303663;5080.390353157644;5370.516653501204;5733.066276404069;5798.362180160095;6111.694415279129;5859.648421094748;6376.075469057054;6384.680765961591;6388.233411990591;6430.424545986263;3551.8101436019224;3982.4026396039667;3995.580464051233;4065.6950728696056;4084.244414783275;5878;5883.323417806836;6111.633302001894;6157.753689852356;6191.452136965759;5430.266913966564;5455.536279416136;5479.8490867752;5758.1650660654805;5889.321543360122;4978.576820970258;5009.194850883517;5500.367457742411;5902.291724993029;5903.49820265265;5118.129917913889;5419.3225846769155;5641.689707065987;5743.477978335828;5844.912326315194;4883.658143929891;5040.269160971712;5917.911231331447;5952.76188952437;6001.90997135052;5414.539764120094;5431.456854314537;5613.831585052455;5716.768288115454;5798.810654140122 12:09:50 INFO - PROCESS | 2565 | |5;yui.html;679.2053297641759;695.7912626212138;716.2121666167213;756.4629113329536;770.1259070954467;14836.10983340999;15288.923555382222;15310.693786124284;15564.221788910565;16326.918365408186;16361.936474129174;16463.012219266853;16532.83467165331;16537.007779533207;16544.420945266917;693.4764252989012;724.8332883436813;879.6804792810768;895.2345744388551;918.5407296351824;924.4591913730476;930.5998420679106;935.2564711054694;939.2908354192575;941.2187884056217;942.2179590939504;959.203860795538;983.4492684096929;987.3384832162468;992.0674565908023;975.9024097590255;976.6532880827275;976.8339382305026;978.5107446276861;979.3976704326788;689.8275431142215;698.2528694297104;723.6888138100614;724.6304384763756;727.6107282603834;14911.850881491264;15044.548663540112;15430.691386172213;15446.691066178613;15495.22523873813;12285.508579656804;12368.876311236863;12387.318697471635;12417.75164496714;12808.551700690525;9866.65466708669;10234.181265498743;10234.33476824004;10278.53746581398;10494.422806745626;3401.2595057034277;3430.811305378203;3437.5015622734563;3494.545709057806;3512.8067956262394;95315;95975;96606;97559.51220243853;97923;9195;9314.115159059878;9338.112879276458;9506.90493095074;10149.137323327452;20066.398008059783;21131;21383.46541336479;21399.572008559742;21440.892795536234;7303.7443689471675;7410.96294518524;7620.771376858703;7658.8085297868;7831.9818423604565;3428.6571342865514;4122.484689413824;4125.319322311785;4133.6073073058615;4144.212599606065;3468.6878180963836;3487.668671476206;3500.43992961125;3567.6967457765863;3569.7501174917506;616.765629060954;620.3672254300589;622.9626222426668;625.6621424430847;643.3727116061896;575.453319346614;586.9325027621793;590.6101972697999;596.2040675697905;631.2298995225817;7787.299143077037;8042.316403105685;8053.315468185153;8212;8275.586220688941;570.3127731084033;584.8303991842319;593.5726277080495;597.6892016151577;609.8231512861221;3777.8488860445823;3811.923761524754;3862.3820188769664;3933.370660694275;3967.7024223183375;92859.53570232105;95207;96216;96695.54956675656;98482;83546.58226708828;84206.57896710477;84343.31313373699;84854.72717909237;84939;72682.63658681673;73028;73274.63362683152;74487.62756186184;75385.62307188429;7553.7716373671;7708;7730.884036739341;7755.457118001686;7831.921680783119;42923.14153716971;43781.34327985146;44641.77679111584;44690.776546117064;45008;45711.02878312899;59648;64162;64680.676596616715;66488;9964.900350996688;10037.734354108952;10214.08073273409;10413.791724165323;10524.210684198562;9780.951095244478;10749.140068794633;10775.299605525615;10827.51276192589;11584.768304633692;9403.730496383174;10083.193344532567;10108.595656173671;10338.483075846027;10681.626143084955;9834.262430317898;9876.209903207582;9880.752981175528;10271.84592231132;10508.211884108876;9952.303338766216;10000.299979001691;10020;10025.849612255968;10198.133158681596;7079.752208672672;8613.87079193825;8667.17661822126;8959.328050396376;9094.727158185266;8593.58248772091;8768.123187681182;8774.912250877409;8865.290776737716;8914.55427228649 12:09:50 INFO - PROCESS | 2565 | __end_tp_report 12:09:50 INFO - PROCESS | 2565 | __start_cc_report 12:09:50 INFO - PROCESS | 2565 | _x_x_mozilla_cycle_collect,420 12:09:50 INFO - PROCESS | 2565 | __end_cc_report 12:09:50 INFO - PROCESS | 2565 | __startTimestamp1463684989986__endTimestamp 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | ------- Summary: start ------- 12:09:50 INFO - PROCESS | 2565 | Number of tests: 6 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | [#0] dojo.html Cycles:165 Average:5859.23 Median:6145.16 stddev:776.48 (12.6%) stddev-sans-first:756.63 12:09:50 INFO - PROCESS | 2565 | Values: 3507.7 3528.5 3535.0 3539.6 3548.6 5776.7 5857.0 5864.0 5963.4 6001.0 5726.5 5887.7 5927.1 5964.9 6037.4 5239.1 5283.9 5378.9 5413.2 5444.7 307.9 5269.4 5334.0 5364.4 5377.1 5320.2 5338.4 5347.3 5364.3 5398.0 5352.9 5389.2 5412.4 5428.6 5437.6 5221.9 5237.9 5311.5 5323.8 5388.6 5606.2 5724.2 5826.3 5851.7 5913.9 6177.8 6219.4 6296.5 6300.0 6304.7 6239.8 6282.7 6342.5 6393.2 6398.0 5111.9 6110.5 6117.2 6137.4 6144.8 6216.2 6357.3 6474.9 6505.0 6556.8 6156.1 6196.3 6206.8 6209.0 6211.6 6307.8 6358.7 6427.3 6429.1 6431.6 5192.6 6215.8 6242.0 6268.4 6313.8 6238.5 6254.8 6292.2 6299.2 6312.8 5165.1 5165.3 5166.8 5175.3 5193.1 4495.8 4511.5 4520.9 4541.9 4618.6 6000.3 6003.5 6047.5 6138.8 6139.7 6340.9 6403.8 6415.0 6417.7 6418.2 5887.6 6032.2 6077.5 6095.6 6105.4 5558.3 5576.4 5594.9 5602.9 5603.5 6320.7 6591.3 6605.3 6606.4 6635.6 6261.1 6331.4 6334.2 6401.0 6409.6 6561.0 6631.3 6647.0 6653.3 6656.3 6142.9 6215.3 6319.4 6334.8 6335.4 6237.2 6318.6 6356.5 6386.5 6390.2 5581.0 6208.9 6280.6 6300.2 6305.8 6118.9 6199.5 6209.0 6293.6 6318.9 6128.4 6184.8 6202.9 6230.7 6232.4 6229.5 6232.2 6242.6 6256.4 6263.7 4937.0 6128.2 6145.2 6145.8 6177.6 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | [#1] ext.html Cycles:180 Average:71224.08 Median:38677.11 stddev:163835.51 (423.6%) stddev-sans-first:164209.06 12:09:50 INFO - PROCESS | 2565 | Values: 500.2 509.3 514.8 528.4 570.5 39160.8 42058.4 42075.0 42152.4 42195.0 42553.8 42755.8 43253.0 43342.8 43442.9 9494.7 9703.8 9711.4 9730.8 9737.1 9601.1 9661.9 9684.5 9713.8 9738.6 8527.4 9726.4 9735.9 9737.8 9747.5 8518.9 9499.4 9653.9 9698.0 9731.4 9490.1 9551.3 9591.3 9597.9 9627.4 40007.8 40144.2 40159.8 40538.0 40562.4 2790.6 2867.0 3071.0 3201.6 3244.3 26854.4 27850.3 28502.6 28838.7 29242.9 2654.9 2677.9 2680.7 2788.9 2869.7 467533.0 486503.0 493426.0 494846.0 494941.0 34377.5 34437.0 34533.3 34628.5 34635.3 33807.5 34440.6 35913.0 36203.5 36962.3 12664.9 14745.0 15080.5 15214.3 15278.5 12880.0 13000.3 13002.7 13013.9 13043.9 6954.2 7041.9 7862.3 8075.6 8108.8 3310.5 3366.7 3372.2 3372.7 3395.0 885.3 930.6 1006.6 1008.8 1041.5 23370.5 29381.0 29538.0 30387.2 30805.4 1044.0 1046.0 1053.3 1063.5 1112.9 15251.3 15298.0 15320.3 15571.5 15595.1 675608.0 692859.0 695495.0 696152.0 700586.0 61298.0 61774.8 62019.1 62073.1 62140.0 1384.9 1411.4 1437.7 1447.2 1448.6 28972.0 29070.0 29281.9 29615.6 29709.6 50671.2 50803.0 50878.0 51637.7 51721.7 613769.0 635075.0 636860.0 637201.0 638859.0 26239.9 26499.9 26676.3 26857.9 26870.3 45270.0 45697.3 46041.0 46529.0 47752.5 36196.5 38604.0 38865.4 38905.8 39044.0 37954.1 38521.6 38583.6 38900.8 39263.6 30426.7 38051.6 38250.6 38750.2 38842.0 34075.8 34219.0 34513.3 34610.0 34893.8 24719.4 24976.4 25181.9 25488.5 25747.5 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | [#2] jquery.html Cycles:180 Average:35888.10 Median:12323.92 stddev:104960.72 (851.7%) stddev-sans-first:105221.31 12:09:50 INFO - PROCESS | 2565 | Values: 385.7 392.9 393.7 397.0 409.3 19524.7 20172.7 20945.0 20990.7 21059.8 20042.0 20112.0 20278.5 20392.9 20686.8 5468.3 5770.6 5774.9 5783.5 5901.4 4902.7 5006.8 5694.4 5825.3 5886.9 5696.9 5797.1 5804.0 5807.7 5820.5 5724.1 5733.9 5787.3 5795.4 5812.6 4884.0 4999.6 5029.1 5031.9 5041.2 20266.8 22483.6 25300.9 25858.5 25898.5 2138.9 2164.3 2254.9 2288.4 2638.2 9138.7 9825.5 9962.2 10052.8 10175.4 1713.0 1739.8 1812.1 1819.3 1877.7 102183.0 132312.0 134379.0 134813.7 136460.0 11415.9 11492.0 11611.9 11801.7 11968.8 14280.9 14389.2 14403.0 14412.5 14426.3 8367.5 8580.1 8595.6 8608.6 8694.3 8354.2 8372.9 8570.4 8591.2 8618.9 4486.5 4528.1 4548.7 4569.7 4595.9 1925.7 1967.1 1972.4 1976.3 2007.5 902.8 908.6 912.5 993.5 1039.8 17793.0 19069.2 20021.8 20069.9 20202.2 801.6 889.9 991.9 1013.6 1051.7 10155.2 10618.8 10627.8 10631.8 10652.3 621248.0 621918.9 622940.8 636512.0 636756.8 114557.0 132636.0 139470.0 140753.0 144132.0 1009.9 1026.0 1043.1 1069.0 1074.6 17749.9 17963.5 18088.0 18260.2 18438.8 54220.0 55373.4 55778.9 56834.0 59673.7 46955.0 68880.7 75236.6 75915.0 77935.0 6067.9 7272.8 7705.8 7735.3 8034.7 10727.4 12090.5 12289.8 12424.2 12569.3 10444.4 10670.2 10712.7 10744.4 10840.5 10284.8 10364.3 10629.8 10733.9 10735.9 10173.4 10342.9 10729.4 10819.4 10864.7 6124.4 7674.8 7689.5 7859.5 7887.4 8863.3 9893.4 10493.9 10930.9 10979.9 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | [#3] mootools.html Cycles:180 Average:5702.54 Median:4648.02 stddev:3264.85 (70.2%) stddev-sans-first:3259.04 12:09:50 INFO - PROCESS | 2565 | Values: 1542.1 1584.4 1798.9 1885.4 1923.1 6841.2 8600.2 8608.9 9076.8 9098.4 8698.9 8816.4 8944.2 8971.6 8980.5 2373.2 2390.8 2409.7 2412.1 2413.6 1934.5 2142.5 2198.0 2379.4 2391.4 2355.6 2383.6 2393.3 2398.2 2402.2 2371.2 2379.8 2381.1 2387.9 2398.4 2371.1 2372.8 2386.5 2392.3 2397.3 8967.1 9007.6 9022.9 9052.5 9054.1 4311.8 5359.0 5454.4 5470.2 5483.2 3364.7 3493.0 3521.4 3537.7 3555.5 1019.9 1042.2 1042.5 1045.2 1060.8 11740.9 11746.9 11858.1 11866.0 11943.6 4535.7 4556.5 4634.0 4641.1 4655.0 9338.3 9342.4 9356.7 9362.3 9375.5 5174.6 5210.8 5226.8 5286.3 5293.1 4421.3 4770.9 4889.8 4900.2 4903.9 3790.6 3835.1 3861.8 3909.9 3961.3 1167.1 1178.3 1179.5 1188.1 1188.3 673.6 892.2 920.1 932.6 947.7 5596.4 5693.9 5728.2 5902.3 5978.4 428.5 435.9 449.8 450.7 451.0 3874.5 3910.8 3979.4 4004.8 4014.9 10316.7 10921.8 10997.7 10998.2 11004.1 10758.3 10850.8 10924.3 10939.0 10958.5 8284.9 8394.1 8404.2 8453.4 8472.6 4845.2 5811.4 5820.1 5831.0 5926.2 10054.3 10154.0 10256.5 10312.7 10354.2 10007.7 10215.7 10489.9 10521.8 10557.2 6544.5 6947.4 7125.8 7141.7 7243.6 8472.4 8498.6 8525.1 8597.3 8610.6 7392.5 7498.7 7566.4 7642.9 7703.4 7406.6 7461.4 7498.8 7501.0 7593.3 5909.7 7082.9 7099.6 7138.9 7147.1 4738.9 4841.2 4877.6 4943.6 5039.1 4902.1 5021.3 5033.6 5079.9 5182.9 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | [#4] prototype.html Cycles:180 Average:4559.12 Median:4892.84 stddev:1436.51 (29.4%) stddev-sans-first:1404.40 12:09:50 INFO - PROCESS | 2565 | Values: 293.3 304.8 318.3 323.3 325.8 4971.3 5256.6 5444.5 5450.5 5757.0 4521.6 5581.2 5602.9 5616.4 5624.9 3764.3 4260.4 4262.9 4265.7 4273.3 4136.8 4205.9 4242.7 4266.3 4268.6 4149.2 4171.7 4173.2 4183.9 4184.9 4088.2 4187.2 4199.6 4199.7 4267.8 3240.8 3654.4 3677.0 3678.7 3686.5 4472.4 5024.5 5293.6 5296.7 5324.9 3973.6 4295.6 4619.5 4849.5 4887.1 4681.5 4898.5 5819.9 5838.3 5840.7 3660.4 3945.8 3965.9 3993.8 3996.6 4783.6 5809.8 6152.8 6254.7 6259.9 3406.2 3727.5 4020.8 4025.9 4061.6 3449.4 3842.5 4076.2 4080.6 4087.3 4095.4 4313.6 4381.9 4745.7 4790.9 4986.6 5385.5 5625.4 5952.3 5961.6 1250.3 1291.3 1337.0 1343.9 1394.3 823.1 827.0 845.9 850.7 858.5 4134.9 4431.9 4484.9 4654.7 4669.3 4225.2 4760.0 4776.9 5087.5 5110.7 4025.6 4429.8 4518.8 4603.8 4658.2 2414.7 2476.4 2524.5 2534.9 2708.9 4496.8 4574.7 4908.2 5154.4 5401.7 4573.6 4912.0 5149.8 5164.3 5223.6 5687.8 5881.4 6364.5 6426.7 6439.7 5245.6 6144.6 6158.8 6165.4 6195.3 5080.4 5370.5 5733.1 5798.4 6111.7 5859.6 6376.1 6384.7 6388.2 6430.4 3551.8 3982.4 3995.6 4065.7 4084.2 5878.0 5883.3 6111.6 6157.8 6191.5 5430.3 5455.5 5479.8 5758.2 5889.3 4978.6 5009.2 5500.4 5902.3 5903.5 5118.1 5419.3 5641.7 5743.5 5844.9 4883.7 5040.3 5917.9 5952.8 6001.9 5414.5 5431.5 5613.8 5716.8 5798.8 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - PROCESS | 2565 | [#5] yui.html Cycles:180 Average:18588.01 Median:32662.02 stddev:27338.40 (83.7%) stddev-sans-first:27382.02 12:09:50 INFO - PROCESS | 2565 | Values: 679.2 695.8 716.2 756.5 770.1 14836.1 15288.9 15310.7 15564.2 16326.9 16361.9 16463.0 16532.8 16537.0 16544.4 693.5 724.8 879.7 895.2 918.5 924.5 930.6 935.3 939.3 941.2 942.2 959.2 983.4 987.3 992.1 975.9 976.7 976.8 978.5 979.4 689.8 698.3 723.7 724.6 727.6 14911.9 15044.5 15430.7 15446.7 15495.2 12285.5 12368.9 12387.3 12417.8 12808.6 9866.7 10234.2 10234.3 10278.5 10494.4 3401.3 3430.8 3437.5 3494.5 3512.8 95315.0 95975.0 96606.0 97559.5 97923.0 9195.0 9314.1 9338.1 9506.9 10149.1 20066.4 21131.0 21383.5 21399.6 21440.9 7303.7 7411.0 7620.8 7658.8 7832.0 3428.7 4122.5 4125.3 4133.6 4144.2 3468.7 3487.7 3500.4 3567.7 3569.8 616.8 620.4 623.0 625.7 643.4 575.5 586.9 590.6 596.2 631.2 7787.3 8042.3 8053.3 8212.0 8275.6 570.3 584.8 593.6 597.7 609.8 3777.8 3811.9 3862.4 3933.4 3967.7 92859.5 95207.0 96216.0 96695.5 98482.0 83546.6 84206.6 84343.3 84854.7 84939.0 72682.6 73028.0 73274.6 74487.6 75385.6 7553.8 7708.0 7730.9 7755.5 7831.9 42923.1 43781.3 44641.8 44690.8 45008.0 45711.0 59648.0 64162.0 64680.7 66488.0 9964.9 10037.7 10214.1 10413.8 10524.2 9781.0 10749.1 10775.3 10827.5 11584.8 9403.7 10083.2 10108.6 10338.5 10681.6 9834.3 9876.2 9880.8 10271.8 10508.2 9952.3 10000.3 10020.0 10025.8 10198.1 7079.8 8613.9 8667.2 8959.3 9094.7 8593.6 8768.1 8774.9 8865.3 8914.6 12:09:50 INFO - PROCESS | 2565 | -------- Summary: end -------- 12:09:50 INFO - PROCESS | 2565 | 12:09:50 INFO - TEST-INFO | 2565: exit 0 12:09:50 INFO - TEST-OK | dromaeo_css | took 1112330ms 12:09:50 INFO - TEST-START | kraken 12:09:50 INFO - operating with platform_type : mac_ 12:09:50 INFO - Initialising browser for kraken test... 12:09:50 INFO - TEST-INFO | started process 2571 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpswWXCx/profile http://localhost:59612/getInfo.html) 12:09:53 INFO - PROCESS | 2571 | __metrics Screen width/height:1600/1200 12:09:53 INFO - PROCESS | 2571 | colorDepth:24 12:09:53 INFO - PROCESS | 2571 | Browser inner width/height: 1024/689 12:09:53 INFO - PROCESS | 2571 | __metrics 12:09:53 ERROR - PROCESS | 2571 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 12:09:54 INFO - TEST-INFO | 2571: exit 0 12:09:54 INFO - Browser initialized. 12:09:54 INFO - Running cycle 1/1 for kraken test... 12:09:54 INFO - TEST-INFO | started process 2574 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpswWXCx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:06 INFO - PROCESS | 2574 | RSS: Main: 249860096 12:10:06 INFO - PROCESS | 2574 | 12:10:09 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59612/tests/kraken/driver.html?testName=audio-beat-detection) 12:10:09 INFO - PROCESS | 2574 | RSS: Main: 261668864 12:10:09 INFO - PROCESS | 2574 | 12:10:12 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59612/tests/kraken/driver.html?testName=audio-dft) 12:10:13 INFO - PROCESS | 2574 | RSS: Main: 266383360 12:10:13 INFO - PROCESS | 2574 | 12:10:16 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59612/tests/kraken/driver.html?testName=audio-fft) 12:10:17 INFO - PROCESS | 2574 | RSS: Main: 266588160 12:10:17 INFO - PROCESS | 2574 | 12:10:19 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59612/tests/kraken/driver.html?testName=audio-oscillator) 12:10:20 INFO - PROCESS | 2574 | RSS: Main: 267800576 12:10:20 INFO - PROCESS | 2574 | 12:10:22 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59612/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:10:22 INFO - PROCESS | 2574 | RSS: Main: 267849728 12:10:22 INFO - PROCESS | 2574 | 12:10:26 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59612/tests/kraken/driver.html?testName=imaging-darkroom) 12:10:26 INFO - PROCESS | 2574 | RSS: Main: 268492800 12:10:26 INFO - PROCESS | 2574 | 12:10:29 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59612/tests/kraken/driver.html?testName=imaging-desaturate) 12:10:30 INFO - PROCESS | 2574 | RSS: Main: 269017088 12:10:30 INFO - PROCESS | 2574 | 12:10:34 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59612/tests/kraken/driver.html?testName=json-parse-financial) 12:10:34 INFO - PROCESS | 2574 | RSS: Main: 267112448 12:10:34 INFO - PROCESS | 2574 | 12:10:36 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59612/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:10:36 INFO - PROCESS | 2574 | RSS: Main: 267026432 12:10:36 INFO - PROCESS | 2574 | 12:10:38 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:10:39 INFO - PROCESS | 2574 | RSS: Main: 267333632 12:10:39 INFO - PROCESS | 2574 | 12:10:41 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:10:42 INFO - PROCESS | 2574 | RSS: Main: 267681792 12:10:42 INFO - PROCESS | 2574 | 12:10:45 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:10:46 INFO - PROCESS | 2574 | RSS: Main: 268345344 12:10:46 INFO - PROCESS | 2574 | 12:10:49 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:10:50 INFO - PROCESS | 2574 | RSS: Main: 268623872 12:10:50 INFO - PROCESS | 2574 | 12:10:51 INFO - PROCESS | 2574 | Cycle 1(1): loaded http://localhost:59612/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59612/tests/kraken/driver.html?testName=ai-astar) 12:10:52 INFO - PROCESS | 2574 | RSS: Main: 270819328 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | __start_tp_report 12:10:52 INFO - PROCESS | 2574 | _x_x_mozilla_page_load 12:10:52 INFO - PROCESS | 2574 | _x_x_mozilla_page_load_details 12:10:52 INFO - PROCESS | 2574 | |i|pagename|runs| 12:10:52 INFO - PROCESS | 2574 | |0;ai-astar;114;115;113;150;114;117;110;116;115;115 12:10:52 INFO - PROCESS | 2574 | |1;audio-beat-detection;156;124;146;199;121;170;138;152;211;140 12:10:52 INFO - PROCESS | 2574 | |2;audio-dft;198;209;219;203;194;200;200;193;200;205 12:10:52 INFO - PROCESS | 2574 | |3;audio-fft;73;85;76;176;57;57;58;65;135;58 12:10:52 INFO - PROCESS | 2574 | |4;audio-oscillator;78;96;146;78;78;78;109;97;117;161 12:10:52 INFO - PROCESS | 2574 | |5;imaging-gaussian-blur;114;132;95;109;135;131;89;86;89;86 12:10:52 INFO - PROCESS | 2574 | |6;imaging-darkroom;141;96;94;95;105;122;95;95;134;106 12:10:52 INFO - PROCESS | 2574 | |7;imaging-desaturate;141;146;99;103;143;146;99;141;149;90 12:10:52 INFO - PROCESS | 2574 | |8;json-parse-financial;60;60;60;60;58;58;59;119;59;58 12:10:52 INFO - PROCESS | 2574 | |9;json-stringify-tinderbox;66;68;75;69;73;77;73;70;71;71 12:10:52 INFO - PROCESS | 2574 | |10;stanford-crypto-aes;108;88;89;88;87;73;107;109;111;88 12:10:52 INFO - PROCESS | 2574 | |11;stanford-crypto-ccm;158;157;148;158;155;153;137;154;255;152 12:10:52 INFO - PROCESS | 2574 | |12;stanford-crypto-pbkdf2;165;169;162;223;171;168;168;167;169;237 12:10:52 INFO - PROCESS | 2574 | |13;stanford-crypto-sha256-iterative;67;66;68;65;69;66;67;70;72;67 12:10:52 INFO - PROCESS | 2574 | __end_tp_report 12:10:52 INFO - PROCESS | 2574 | __start_cc_report 12:10:52 INFO - PROCESS | 2574 | _x_x_mozilla_cycle_collect,1078 12:10:52 INFO - PROCESS | 2574 | __end_cc_report 12:10:52 INFO - PROCESS | 2574 | __startTimestamp1463685052096__endTimestamp 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | ------- Summary: start ------- 12:10:52 INFO - PROCESS | 2574 | Number of tests: 14 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#0] ai-astar Cycles:10 Average:117.90 Median:115.00 stddev:11.44 (9.9%) stddev-sans-first:12.04 12:10:52 INFO - PROCESS | 2574 | Values: 114.0 115.0 113.0 150.0 114.0 117.0 110.0 116.0 115.0 115.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#1] audio-beat-detection Cycles:10 Average:155.70 Median:154.00 stddev:29.84 (19.4%) stddev-sans-first:31.65 12:10:52 INFO - PROCESS | 2574 | Values: 156.0 124.0 146.0 199.0 121.0 170.0 138.0 152.0 211.0 140.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#2] audio-dft Cycles:10 Average:202.10 Median:201.50 stddev:7.61 (3.8%) stddev-sans-first:7.92 12:10:52 INFO - PROCESS | 2574 | Values: 198.0 209.0 219.0 203.0 194.0 200.0 200.0 193.0 200.0 205.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#3] audio-fft Cycles:10 Average:84.00 Median:61.50 stddev:40.03 (65.1%) stddev-sans-first:42.26 12:10:52 INFO - PROCESS | 2574 | Values: 73.0 85.0 76.0 176.0 57.0 57.0 58.0 65.0 135.0 58.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:78.00 stddev:29.86 (38.3%) stddev-sans-first:30.17 12:10:52 INFO - PROCESS | 2574 | Values: 78.0 96.0 146.0 78.0 78.0 78.0 109.0 97.0 117.0 161.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#5] imaging-gaussian-blur Cycles:10 Average:106.60 Median:86.00 stddev:20.30 (23.6%) stddev-sans-first:21.36 12:10:52 INFO - PROCESS | 2574 | Values: 114.0 132.0 95.0 109.0 135.0 131.0 89.0 86.0 89.0 86.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:94.50 stddev:17.70 (18.7%) stddev-sans-first:14.28 12:10:52 INFO - PROCESS | 2574 | Values: 141.0 96.0 94.0 95.0 105.0 122.0 95.0 95.0 134.0 106.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#7] imaging-desaturate Cycles:10 Average:125.70 Median:147.50 stddev:24.38 (16.5%) stddev-sans-first:25.22 12:10:52 INFO - PROCESS | 2574 | Values: 141.0 146.0 99.0 103.0 143.0 146.0 99.0 141.0 149.0 90.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:59.50 stddev:18.96 (31.9%) stddev-sans-first:20.02 12:10:52 INFO - PROCESS | 2574 | Values: 60.0 60.0 60.0 60.0 58.0 58.0 59.0 119.0 59.0 58.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:3.30 (4.6%) stddev-sans-first:2.89 12:10:52 INFO - PROCESS | 2574 | Values: 66.0 68.0 75.0 69.0 73.0 77.0 73.0 70.0 71.0 71.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:87.50 stddev:12.89 (14.7%) stddev-sans-first:12.76 12:10:52 INFO - PROCESS | 2574 | Values: 108.0 88.0 89.0 88.0 87.0 73.0 107.0 109.0 111.0 88.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#11] stanford-crypto-ccm Cycles:10 Average:162.70 Median:156.00 stddev:33.03 (21.2%) stddev-sans-first:34.98 12:10:52 INFO - PROCESS | 2574 | Values: 158.0 157.0 148.0 158.0 155.0 153.0 137.0 154.0 255.0 152.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:169.00 stddev:26.72 (15.8%) stddev-sans-first:27.79 12:10:52 INFO - PROCESS | 2574 | Values: 165.0 169.0 162.0 223.0 171.0 168.0 168.0 167.0 169.0 237.0 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - PROCESS | 2574 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.50 stddev:2.11 (3.1%) stddev-sans-first:2.22 12:10:52 INFO - PROCESS | 2574 | Values: 67.0 66.0 68.0 65.0 69.0 66.0 67.0 70.0 72.0 67.0 12:10:52 INFO - PROCESS | 2574 | -------- Summary: end -------- 12:10:52 INFO - PROCESS | 2574 | 12:10:52 INFO - TEST-INFO | 2574: exit 0 12:10:52 INFO - TEST-OK | kraken | took 62369ms 12:10:52 INFO - SUITE-END | took 1174s 12:10:53 INFO - Completed test suite (00:19:35) 12:10:53 INFO - javascript benchmark 12:10:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7076.4811308822955, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3507.6843084122424, 3528.488369186467, 3534.9646503534964, 3539.646035396461, 3548.6451354864503, 5776.682282474468, 5857.0, 5863.9999999999945, 5963.373845746189, 6001.0, 5726.484616384525, 5887.735051922663, 5927.081302398135, 5964.880702385961, 6037.3660765619525, 5239.056969745444, 5283.86790330241, 5378.946210537903, 5413.188021796723, 5444.727763611823, 307.8948746197266, 5269.367675878881, 5334.013207556595, 5364.409914909357, 5377.059014672436, 5320.201969704536, 5338.439463856317, 5347.278117454132, 5364.275822763916, 5398.0, 5352.892942141136, 5389.191621256804, 5412.431694672042, 5428.592855535811, 5437.592180586433, 5221.947780522184, 5237.921431178535, 5311.468853114697, 5323.760430780624, 5388.5958553108285, 5606.243157173811, 5724.227229324071, 5826.300843898759, 5851.707414629294, 5913.8817223655715, 6177.845553861144, 6219.440250377443, 6296.527760417941, 6300.0, 6304.653244071575, 6239.812805615838, 6282.685865706742, 6342.5243106767175, 6393.1688880445245, 6398.040293955897, 5111.897762044739, 6110.450059494667, 6117.235345581802, 6137.41694539018, 6144.815655530297, 6216.222972128484, 6357.332480089524, 6474.870502589922, 6504.9674751625935, 6556.836079097965, 6156.138140660311, 6196.318404975449, 6206.844828879224, 6209.037599172136, 6211.596246243979, 6307.779227727007, 6358.7456501740335, 6427.260865000585, 6429.067785171124, 6431.646259455775, 5192.584593232532, 6215.782447614402, 6242.0, 6268.373162683786, 6313.779017734358, 6238.500919926395, 6254.781082662085, 6292.1505596743855, 6299.244090709144, 6312.810615681537, 5165.070287348238, 5165.276861239429, 5166.819161329336, 5175.275461435402, 5193.1171700810955, 4495.752733599618, 4511.458624965026, 4520.9095818084115, 4541.909161816811, 4618.607418369476, 6000.279966403972, 6003.519718422603, 6047.516198704093, 6138.815835524942, 6139.693015349215, 6340.858347804515, 6403.807885763435, 6415.037744338293, 6417.679116044273, 6418.1656384669695, 5887.587868849139, 6032.155498230251, 6077.483413909778, 6095.634261944208, 6105.389461053859, 5558.277423934944, 5576.414476479992, 5594.916076258859, 5602.887942241132, 5603.495685388335, 6320.715567799552, 6591.307912669101, 6605.339466053356, 6606.372394622503, 6635.635040072746, 6261.123442717932, 6331.398517140864, 6334.1765570475545, 6401.007843784221, 6409.647469389229, 6561.020581053433, 6631.33686631333, 6647.0, 6653.301403352578, 6656.334366563498, 6142.897836592492, 6215.316315205233, 6319.368063193644, 6334.778282760266, 6335.366463353628, 6237.220347456568, 6318.62088274705, 6356.459700925473, 6386.457151142205, 6390.201224846895, 5580.972095139498, 6208.875822483616, 6280.560360774884, 6300.243970723543, 6305.842353941188, 6118.862843290023, 6199.473044791062, 6209.006558950547, 6293.55945083858, 6318.936810631835, 6128.417800308964, 6184.783532576338, 6202.883480973468, 6230.719617617308, 6232.376762323732, 6229.50163986889, 6232.220972378453, 6242.625442473466, 6256.436920677161, 6263.655498947602, 4936.950630493793, 6128.228138398679, 6145.1564530639735, 6145.832291864532, 6177.598456100339], "value": 5813.559511083776, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.24712807225126, 509.30225590940415, 514.7799714676217, 528.4292963599313, 570.5207625594505, 39160.825175244754, 42058.369124463155, 42075.0, 42152.36771448431, 42195.0, 42553.787231063805, 42755.78622106893, 43253.0, 43342.783286083606, 43442.913927151836, 9494.66768663097, 9703.805923881519, 9711.417314961089, 9730.805383892319, 9737.07497787709, 9601.1358977692, 9661.855072173888, 9684.467354295512, 9713.805723885518, 9738.610455581804, 8527.403081784247, 9726.416415015121, 9735.90264097357, 9737.756556086119, 9747.512624368788, 8518.914810851875, 9499.382540134939, 9653.855192172123, 9697.951510242474, 9731.41611503312, 9490.050994900525, 9551.331406801526, 9591.328606997513, 9597.856032159454, 9627.374220675634, 40007.79996100001, 40144.19711605781, 40159.7992010041, 40538.0, 40562.39156412656, 2790.55033929093, 2866.985665071682, 3071.032624723205, 3201.5677883485664, 3244.3186930744337, 26854.413374465083, 27850.3037424064, 28502.572461413096, 28838.711612883813, 29242.853785730935, 2654.9070782522713, 2677.8928842846262, 2680.745329193743, 2788.9442211155865, 2869.741723244897, 467533.0, 486503.0, 493426.0, 494846.0, 494940.9999999964, 34377.48433773495, 34437.0, 34533.30933381318, 34628.48057279143, 34635.307293853984, 33807.492887606706, 34440.622375104715, 35913.0, 36203.4569481458, 36962.26075478475, 12664.936675316565, 14745.041572297767, 15080.472183473526, 15214.315355809014, 15278.465253716067, 12879.969602431784, 13000.284984325954, 13002.739945201043, 13013.869861301266, 13043.869561304455, 6954.165500140016, 7041.859162816715, 7862.2923936845955, 8075.636596353178, 8108.83782324362, 3310.536524886518, 3366.6801653842845, 3372.2295794707975, 3372.662733726657, 3394.983025084908, 885.2829208341135, 930.5533343994921, 1006.6225165562914, 1008.8234558952154, 1041.4638408347778, 23370.532589348117, 29381.0, 29538.0, 30387.2403189922, 30805.383892322476, 1044.0059486381128, 1045.9738506537396, 1053.3311347294523, 1063.4576366053368, 1112.8887111288968, 15251.313690883937, 15298.005629634039, 15320.310586023656, 15571.454999074978, 15595.064296142267, 675608.0, 692859.0, 695495.0, 696152.0, 700586.0, 61298.0, 61774.76450470965, 62019.069713954326, 62073.06890396648, 62140.0, 1384.8892088632886, 1411.4072089722342, 1437.6837095838898, 1447.2474313356995, 1448.5943935698233, 28972.0, 29070.0, 29281.85359073191, 29615.555766663947, 29709.5543566851, 50671.23993140032, 50803.0, 50878.0, 51637.7418112907, 51721.7413912928, 613769.0, 635075.0, 636860.0, 637201.0, 638859.0, 26239.868800655873, 26499.867500662374, 26676.333091672863, 26857.86571067132, 26870.32824179333, 45270.0, 45697.31454028126, 46041.0, 46529.0, 47752.5224747762, 36196.45705314475, 38604.0, 38865.41701874418, 38905.83282501529, 39044.0, 37954.051148721504, 38521.61478385292, 38583.61416385912, 38900.80549597347, 39263.60736392599, 30426.695733042387, 38051.61948380481, 38250.617493824706, 38750.224995500495, 38842.0, 34075.829620851735, 34219.0, 34513.30973380568, 34610.0, 34893.77871774475, 24719.38201544976, 24976.37559061038, 25181.87409062943, 25488.490230195664, 25747.485050298514], "value": 18368.30680204272, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.6818125046837, 392.88017154767795, 393.742098925204, 397.0451065188223, 409.3409610527051, 19524.707129393053, 20172.69740953887, 20944.9527523624, 20990.685139722915, 21059.789402105973, 20042.0, 20112.0, 20278.493037674067, 20392.87839168849, 20686.793132068673, 5468.343798744147, 5770.596058275923, 5774.942250577504, 5783.5084017858535, 5901.439363260483, 4902.730349830758, 5006.849794506171, 5694.373618901917, 5825.300963884319, 5886.941130588682, 5696.8860622787515, 5797.101449275369, 5803.95528804815, 5807.680577568232, 5820.505257053155, 5724.055530837407, 5733.942660573382, 5787.305523337184, 5795.420457954213, 5812.593118481729, 4883.975580122112, 4999.550040496373, 5029.1450453423, 5031.949680503185, 5041.218611115283, 20266.79733202664, 22483.550328993493, 25300.873495632404, 25858.482830343477, 25898.482030359475, 2138.9358319250446, 2164.285785690718, 2254.9098036078535, 2288.39357570244, 2638.2085374387607, 9138.725838224798, 9825.508724563742, 9962.203023758082, 10052.849207261825, 10175.440350780704, 1712.9636569875295, 1739.843414092725, 1812.0848971269556, 1819.2631984046482, 1877.7465042219262, 102183.0, 132312.0153197703, 134379.0, 134813.6518634811, 136460.0, 11415.885841141482, 11492.0, 11611.88388116125, 11801.704957375963, 11968.760624787456, 14280.928595357165, 14389.208593527457, 14402.991790574559, 14412.495562655466, 14426.278686065654, 8367.539785311745, 8580.14198580137, 8595.5702214889, 8608.569571521399, 8694.26098781598, 8354.164583541597, 8372.889577445321, 8570.4000719949, 8591.226789588967, 8618.870716939251, 4486.461624605068, 4528.071745292207, 4548.681592288508, 4569.697636173741, 4595.885102872455, 1925.740025096594, 1967.0558132096673, 1972.3688419705843, 1976.2687805512053, 2007.498125468633, 902.7697937026016, 908.6092980018656, 912.4844462878452, 993.5479356892597, 1039.77644806367, 17793.0, 19069.237230510902, 20021.799782002287, 20069.899650501946, 20202.191912323637, 801.6292140439797, 889.8876404494382, 991.9206463482905, 1013.5692330759398, 1051.7318083888713, 10155.187584993182, 10618.840717389245, 10627.787444251071, 10631.767328860871, 10652.254342195973, 621248.0, 621918.8904055541, 622940.7705922973, 636512.0, 636756.816215916, 114557.0, 132636.01045984318, 139470.0, 140753.0, 144132.0, 1009.9192064634811, 1025.9538320775434, 1043.0716662170673, 1069.0325255643654, 1074.5809134437575, 17749.93500389981, 17963.461096167135, 18088.0, 18260.178291977158, 18438.801477903893, 54220.0, 55373.446265536826, 55778.88442231214, 56834.0, 59673.70163149157, 46955.0, 68880.6555967217, 75236.62381688057, 75915.0, 77935.0, 6067.878642427038, 7272.781816545512, 7705.845883082419, 7735.303822655988, 8034.67861285542, 10727.40999245049, 12090.516379344728, 12289.754204916031, 12424.212403408037, 12569.308688021882, 10444.373337599769, 10670.19973502006, 10712.73218169552, 10744.409057501913, 10840.457977100956, 10284.794304113726, 10364.274500784873, 10629.787404251718, 10733.946330268298, 10735.89264107349, 10173.44046077473, 10342.948285258526, 10729.409882456539, 10819.404932728776, 10864.674059778219, 6124.356942521149, 7674.769756907302, 7689.50018248812, 7859.52842829432, 7887.369010479262, 8863.335249856416, 9893.35693179941, 10493.9475302623, 10930.945345273542, 10979.945100274448], "value": 9402.57665969327, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1542.1364036139762, 1584.413766906245, 1798.937037203698, 1885.406097079419, 1923.1153669312116, 6841.213260475047, 8600.182982616649, 8608.913910860889, 9076.773080672978, 9098.40860344078, 8698.869516957251, 8816.382853200279, 8944.195022447979, 8971.596278167483, 8980.506072166028, 2373.2049763329264, 2390.7728765767224, 2409.71083469983, 2412.1316326122583, 2413.553492603874, 1934.5260411199276, 2142.4965133193687, 2198.0438509248447, 2379.4170428245106, 2391.390195500145, 2355.646653002055, 2383.5828729972186, 2393.3059412770276, 2398.244552965819, 2402.159244264511, 2371.2412028150998, 2379.8453100548495, 2381.095183830142, 2387.9164229252065, 2398.424378149249, 2371.098982386682, 2372.7745864143053, 2386.4630458146953, 2392.3062311929693, 2397.29279862441, 8967.103289671046, 9007.594658240396, 9022.909770902339, 9052.456852588866, 9054.094590540893, 4311.82752689892, 5358.98179345923, 5454.372747134051, 5470.17947307903, 5483.232347471358, 3364.713999310062, 3492.982535087334, 3521.3837578423672, 3537.726418489342, 3555.520004799371, 1019.9133073688671, 1042.155853758458, 1042.5100202904623, 1045.221310123955, 1060.7878424315168, 11740.943315101596, 11746.941265293704, 11858.051355891508, 11865.991390731799, 11943.641690749379, 4535.659825513099, 4556.453225612881, 4633.9536604634195, 4641.094986477605, 4654.953450465452, 9338.252939764803, 9342.392744471723, 9356.672516461891, 9362.297827662816, 9375.48434836077, 5174.611904107207, 5210.76551555181, 5226.843194704165, 5286.259923610698, 5293.073712100367, 4421.336799480039, 4770.904581908412, 4889.804407823718, 4900.215965445512, 4903.926441103386, 3790.6019867914024, 3835.0987517933263, 3861.768293902375, 3909.9218015639526, 3961.267165574378, 1167.095500986725, 1178.2871362825538, 1179.4633441783967, 1188.1089183112665, 1188.3285943441927, 673.6093066021696, 892.1702816380828, 920.1120918313859, 932.5570354081757, 947.6920000999703, 5596.412376700469, 5693.943060569424, 5728.198052272685, 5902.291724992942, 5978.432048955342, 428.5371798457658, 435.8910272431892, 449.7885993583011, 450.73181457033667, 450.9616682582017, 3874.4575759393706, 3910.804459777, 3979.422983667352, 4004.799760012046, 4014.8996275092773, 10316.742081448025, 10921.781564368668, 10997.725056873642, 10998.230123891411, 11004.119670426348, 10758.300710453796, 10850.782984340269, 10924.344539327665, 10939.0, 10958.506867190996, 8284.875726864102, 8394.076651568323, 8404.20160084791, 8453.408261421642, 8472.618732157067, 4845.224764037737, 5811.418858114154, 5820.126980952891, 5830.970845145747, 5926.170336152856, 10054.29619926598, 10153.984601539787, 10256.48717564104, 10312.742181445525, 10354.17166626653, 10007.749806254902, 10215.744606384602, 10489.947550262199, 10521.78956420852, 10557.20820938448, 6544.4764418845425, 6947.3747362737595, 7125.786226413215, 7141.714331426685, 7243.637818108968, 8472.3645726572, 8498.617562209593, 8525.104863989192, 8597.26923211509, 8610.569471526273, 7392.519486233593, 7498.700051997859, 7566.35685966699, 7642.885356719543, 7703.422243331661, 7406.629668516445, 7461.4403919705155, 7498.812529686801, 7501.0, 7593.278638529331, 5909.674967876636, 7082.893756593759, 7099.609521476162, 7138.857222855618, 7147.142342918883, 4738.85783426484, 4841.176999909953, 4877.6341774367775, 4943.604511638989, 5039.0929632666475, 4902.068606964665, 5021.279996802993, 5033.622478314068, 5079.949200507947, 5182.8963420732125], "value": 4482.464572871317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3370582483587, 304.83386554327876, 318.26639595731837, 323.2823132645526, 325.77033191599924, 4971.304017437557, 5256.579473642109, 5444.455554444554, 5450.5367043801325, 5756.992526307889, 4521.638268938485, 5581.1815033408075, 5602.915956260649, 5616.354119276284, 5624.887502249952, 3764.3036038332857, 4260.3822445745345, 4262.850800221985, 4265.7014009019385, 4273.252180868351, 4136.772477513736, 4205.873823785276, 4242.666533406629, 4266.317389217726, 4268.551802060785, 4149.211649786546, 4171.728837625545, 4173.1862286854075, 4183.895402614944, 4184.916301673965, 4088.2232375848494, 4187.194579631654, 4199.580041995791, 4199.664026877843, 4267.829286828523, 3240.789348692351, 3654.433562797744, 3677.0, 3678.6505281998166, 3686.5207523022104, 4472.373867658531, 5024.522670346311, 5293.550048245904, 5296.735163241861, 5324.923279920834, 3973.6290979611986, 4295.591918767712, 4619.514950930137, 4849.466558678542, 4887.144749668793, 4681.46163191234, 4898.534639209305, 5819.912701309484, 5838.2702162229725, 5840.678762668052, 3660.4143337066207, 3945.822437990297, 3965.940510892339, 3993.7603743775467, 3996.640302372771, 4783.579740485535, 5809.779946211301, 6152.815415537541, 6254.655993920333, 6259.874802503924, 3406.2457834215397, 3727.496787933594, 4020.778857162884, 4025.8993525162105, 4061.573534778806, 3449.3618680544178, 3842.51968503937, 4076.184763047402, 4080.571539988318, 4087.305158123126, 4095.365218391154, 4313.61177494027, 4381.890452738707, 4745.7051368510865, 4790.928136077961, 4986.576141027981, 5385.542228910508, 5625.353084395347, 5952.255968003999, 5961.642301461839, 1250.3123282194756, 1291.2704322058007, 1337.0, 1343.9114317402934, 1394.3237529798039, 823.0822632764545, 826.9539033122984, 845.9383473740417, 850.6639877248452, 858.5492616376453, 4134.911869505941, 4431.8670439886855, 4484.910301793946, 4654.720716757005, 4669.322948172496, 4225.218334608107, 4760.0, 4776.94907120433, 5087.46421237244, 5110.66780659256, 4025.5571887092397, 4429.80065897029, 4518.81924723014, 4603.792829322689, 4658.1848176568965, 2414.6740190074465, 2476.4056626409892, 2524.482481091372, 2534.9493010140063, 2708.932276693059, 4496.775161241925, 4574.679772415899, 4908.239222920453, 5154.3814742230425, 5401.7299135043095, 4573.588377046081, 4912.0, 5149.819756308511, 5164.347408829139, 5223.641853118141, 5687.800926967537, 5881.41185881417, 6364.490840732823, 6426.678666066678, 6439.710213040518, 5245.632805703564, 6144.631322120687, 6158.753649853956, 6165.383461653799, 6195.349488303663, 5080.390353157644, 5370.516653501204, 5733.066276404069, 5798.362180160095, 6111.694415279129, 5859.648421094748, 6376.075469057054, 6384.680765961591, 6388.233411990591, 6430.424545986263, 3551.8101436019224, 3982.4026396039667, 3995.580464051233, 4065.6950728696056, 4084.244414783275, 5878.0, 5883.323417806836, 6111.633302001894, 6157.753689852356, 6191.452136965759, 5430.266913966564, 5455.536279416136, 5479.8490867752, 5758.1650660654805, 5889.321543360122, 4978.576820970258, 5009.194850883517, 5500.367457742411, 5902.291724993029, 5903.49820265265, 5118.129917913889, 5419.3225846769155, 5641.689707065987, 5743.477978335828, 5844.912326315194, 4883.658143929891, 5040.269160971712, 5917.911231331447, 5952.76188952437, 6001.90997135052, 5414.539764120094, 5431.456854314537, 5613.831585052455, 5716.768288115454, 5798.810654140122], "value": 4101.026260931938, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.2053297641759, 695.7912626212138, 716.2121666167213, 756.4629113329536, 770.1259070954467, 14836.10983340999, 15288.923555382222, 15310.693786124284, 15564.221788910565, 16326.918365408186, 16361.936474129174, 16463.012219266853, 16532.83467165331, 16537.007779533207, 16544.420945266917, 693.4764252989012, 724.8332883436813, 879.6804792810768, 895.2345744388551, 918.5407296351824, 924.4591913730476, 930.5998420679106, 935.2564711054694, 939.2908354192575, 941.2187884056217, 942.2179590939504, 959.203860795538, 983.4492684096929, 987.3384832162468, 992.0674565908023, 975.9024097590255, 976.6532880827275, 976.8339382305026, 978.5107446276861, 979.3976704326788, 689.8275431142215, 698.2528694297104, 723.6888138100614, 724.6304384763756, 727.6107282603834, 14911.850881491264, 15044.548663540112, 15430.691386172213, 15446.691066178613, 15495.22523873813, 12285.508579656804, 12368.876311236863, 12387.318697471635, 12417.75164496714, 12808.551700690525, 9866.65466708669, 10234.181265498743, 10234.33476824004, 10278.53746581398, 10494.422806745626, 3401.2595057034277, 3430.811305378203, 3437.5015622734563, 3494.545709057806, 3512.8067956262394, 95315.0, 95975.0, 96606.0, 97559.51220243853, 97923.0, 9195.0, 9314.115159059878, 9338.112879276458, 9506.90493095074, 10149.137323327452, 20066.398008059783, 21131.0, 21383.46541336479, 21399.572008559742, 21440.892795536234, 7303.7443689471675, 7410.96294518524, 7620.771376858703, 7658.8085297868, 7831.9818423604565, 3428.6571342865514, 4122.484689413824, 4125.319322311785, 4133.6073073058615, 4144.212599606065, 3468.6878180963836, 3487.668671476206, 3500.43992961125, 3567.6967457765863, 3569.7501174917506, 616.765629060954, 620.3672254300589, 622.9626222426668, 625.6621424430847, 643.3727116061896, 575.453319346614, 586.9325027621793, 590.6101972697999, 596.2040675697905, 631.2298995225817, 7787.299143077037, 8042.316403105685, 8053.315468185153, 8212.0, 8275.586220688941, 570.3127731084033, 584.8303991842319, 593.5726277080495, 597.6892016151577, 609.8231512861221, 3777.8488860445823, 3811.923761524754, 3862.3820188769664, 3933.370660694275, 3967.7024223183375, 92859.53570232105, 95207.0, 96216.0, 96695.54956675656, 98482.0, 83546.58226708828, 84206.57896710477, 84343.31313373699, 84854.72717909237, 84939.0, 72682.63658681673, 73028.0, 73274.63362683152, 74487.62756186184, 75385.62307188429, 7553.7716373671, 7708.0, 7730.884036739341, 7755.457118001686, 7831.921680783119, 42923.14153716971, 43781.34327985146, 44641.77679111584, 44690.776546117064, 45008.0, 45711.02878312899, 59648.0, 64162.0, 64680.676596616715, 66488.0, 9964.900350996688, 10037.734354108952, 10214.08073273409, 10413.791724165323, 10524.210684198562, 9780.951095244478, 10749.140068794633, 10775.299605525615, 10827.51276192589, 11584.768304633692, 9403.730496383174, 10083.193344532567, 10108.595656173671, 10338.483075846027, 10681.626143084955, 9834.262430317898, 9876.209903207582, 9880.752981175528, 10271.84592231132, 10508.211884108876, 9952.303338766216, 10000.299979001691, 10020.0, 10025.849612255968, 10198.133158681596, 7079.752208672672, 8613.87079193825, 8667.17661822126, 8959.328050396376, 9094.727158185266, 8593.58248772091, 8768.123187681182, 8774.912250877409, 8865.290776737716, 8914.55427228649], "value": 6802.893159469107, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1645.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 150.0, 114.0, 117.0, 110.0, 116.0, 115.0, 115.0], "value": 117.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 146.0, 199.0, 121.0, 170.0, 138.0, 152.0, 211.0, 140.0], "value": 155.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 209.0, 219.0, 203.0, 194.0, 200.0, 200.0, 193.0, 200.0, 205.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 85.0, 76.0, 176.0, 57.0, 57.0, 58.0, 65.0, 135.0, 58.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 96.0, 146.0, 78.0, 78.0, 78.0, 109.0, 97.0, 117.0, 161.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 132.0, 95.0, 109.0, 135.0, 131.0, 89.0, 86.0, 89.0, 86.0], "value": 106.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 96.0, 94.0, 95.0, 105.0, 122.0, 95.0, 95.0, 134.0, 106.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 99.0, 103.0, 143.0, 146.0, 99.0, 141.0, 149.0, 90.0], "value": 125.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 58.0, 58.0, 59.0, 119.0, 59.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 75.0, 69.0, 73.0, 77.0, 73.0, 70.0, 71.0, 71.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 88.0, 89.0, 88.0, 87.0, 73.0, 107.0, 109.0, 111.0, 88.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 157.0, 148.0, 158.0, 155.0, 153.0, 137.0, 154.0, 255.0, 152.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 162.0, 223.0, 171.0, 168.0, 168.0, 167.0, 169.0, 237.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 65.0, 69.0, 66.0, 67.0, 70.0, 72.0, 67.0], "value": 67.7, "unit": "score"}]}]} 12:10:53 INFO - Return code: 0 12:10:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:10:53 ERROR - # TBPL SUCCESS # 12:10:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:53 INFO - 2016-05-19 12:10:53.399 screenresolution[2579:37987] starting screenresolution argv=screenresolution get 12:10:53 INFO - 2016-05-19 12:10:53.401 screenresolution[2579:37987] Display 0: 1600x1200x32@60 12:10:53 INFO - 2016-05-19 12:10:53.407 screenresolution[2580:37990] starting screenresolution argv=screenresolution list 12:10:53 INFO - Available Modes on Display 0 12:10:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:53 INFO - Intel Iris: 12:10:53 INFO - Chipset Model: Intel Iris 12:10:53 INFO - Type: GPU 12:10:53 INFO - Bus: Built-In 12:10:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:53 INFO - Vendor: Intel (0x8086) 12:10:53 INFO - Device ID: 0x0a2e 12:10:53 INFO - Revision ID: 0x0009 12:10:53 INFO - Displays: 12:10:53 INFO - Display: 12:10:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:53 INFO - Display Serial Number: 1600x1200 60 12:10:53 INFO - Main Display: Yes 12:10:53 INFO - Mirror: Off 12:10:53 INFO - Online: Yes 12:10:53 INFO - Rotation: Supported 12:10:53 INFO - Return code: 0 12:10:53 INFO - Running post-action listener: _resource_record_post_action 12:10:53 INFO - ##### 12:10:53 INFO - ##### Finished run-tests step (success) 12:10:53 INFO - ##### 12:10:53 INFO - Running post-run listener: _resource_record_post_run 12:10:53 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 237568; Write bytes: 699147264; Read time: 1; Write time: 1931 12:10:53 INFO - TinderboxPrint: CPU usage
54.3% 12:10:53 INFO - TinderboxPrint: I/O read bytes / time
237,568 / 1 12:10:53 INFO - TinderboxPrint: I/O write bytes / time
699,147,264 / 1,931 12:10:53 INFO - TinderboxPrint: CPU idle
2,211.5 (45.7%) 12:10:53 INFO - TinderboxPrint: CPU system
975.2 (20.1%) 12:10:53 INFO - TinderboxPrint: CPU user
1,655.0 (34.2%) 12:10:53 INFO - TinderboxPrint: Swap in / out
395,747,328 / 0 12:10:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381542912; Write bytes: 375896064; Read time: 28509; Write time: 746 12:10:53 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 237568; Write bytes: 300379136; Read time: 1; Write time: 1132 12:10:54 INFO - Running post-run listener: _upload_blobber_files 12:10:54 INFO - Blob upload gear active. 12:10:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:10:54 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 12:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:10:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:10:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:10:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:10:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:57 INFO - (blobuploader) - INFO - Done attempting. 12:10:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:10:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:10:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:10:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:10:58 INFO - (blobuploader) - INFO - Done attempting. 12:10:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:10:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:10:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:11:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:11:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:11:00 INFO - (blobuploader) - INFO - Done attempting. 12:11:00 INFO - (blobuploader) - INFO - Iteration through files over. 12:11:00 INFO - Return code: 0 12:11:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:11:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:11:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/564ff50e98e236256390576f1223560e8fd19d18a1efa77bdecd1da71f3ecd687e3bbc8e1787d3e8b8ff5c6107c03a2006f39b83f776f6481cb146eb0c971efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a7a9ec6a8814fb438d36614c59a03a64b8c97d2d7e508a08b4706db6dd298dcc7006ac1e10a0f1ad6f8a63f2a8145a6ce998ac4b2f95728c996e4fd73f07a07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c877ed3c33b2bf50436e36d4ad9699cb5471766888fc71fde894279f5cd429345816d1e50fdaeb3432ec6c51b7f0868b83d7cfb5d710787cbb1dd6fe52966d54"} 12:11:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:11:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:11:00 INFO - Contents: 12:11:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/564ff50e98e236256390576f1223560e8fd19d18a1efa77bdecd1da71f3ecd687e3bbc8e1787d3e8b8ff5c6107c03a2006f39b83f776f6481cb146eb0c971efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a7a9ec6a8814fb438d36614c59a03a64b8c97d2d7e508a08b4706db6dd298dcc7006ac1e10a0f1ad6f8a63f2a8145a6ce998ac4b2f95728c996e4fd73f07a07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c877ed3c33b2bf50436e36d4ad9699cb5471766888fc71fde894279f5cd429345816d1e50fdaeb3432ec6c51b7f0868b83d7cfb5d710787cbb1dd6fe52966d54"} 12:11:00 INFO - Running post-run listener: copy_logs_to_upload_dir 12:11:00 INFO - Copying logs to upload dir... 12:11:00 INFO - mkdir: /builds/slave/test/build/upload/logs 12:11:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1507.276480 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 7 secs) (at 2016-05-19 12:11:00.724375) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 12:11:00.727930) ========= 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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/564ff50e98e236256390576f1223560e8fd19d18a1efa77bdecd1da71f3ecd687e3bbc8e1787d3e8b8ff5c6107c03a2006f39b83f776f6481cb146eb0c971efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a7a9ec6a8814fb438d36614c59a03a64b8c97d2d7e508a08b4706db6dd298dcc7006ac1e10a0f1ad6f8a63f2a8145a6ce998ac4b2f95728c996e4fd73f07a07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c877ed3c33b2bf50436e36d4ad9699cb5471766888fc71fde894279f5cd429345816d1e50fdaeb3432ec6c51b7f0868b83d7cfb5d710787cbb1dd6fe52966d54"} build_url:https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011459 build_url: 'https://queue.taskcluster.net/v1/task/GBImwxvlRdyfwPXyxkvazA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/564ff50e98e236256390576f1223560e8fd19d18a1efa77bdecd1da71f3ecd687e3bbc8e1787d3e8b8ff5c6107c03a2006f39b83f776f6481cb146eb0c971efa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a7a9ec6a8814fb438d36614c59a03a64b8c97d2d7e508a08b4706db6dd298dcc7006ac1e10a0f1ad6f8a63f2a8145a6ce998ac4b2f95728c996e4fd73f07a07", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c877ed3c33b2bf50436e36d4ad9699cb5471766888fc71fde894279f5cd429345816d1e50fdaeb3432ec6c51b7f0868b83d7cfb5d710787cbb1dd6fe52966d54"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 12:11:00.757338) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:11:00.757679) ========= 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.IfOgzo5zGQ/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.N1LLP49ca1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.005483 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:11:00.806456) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 12:11:00.806811) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 12:11:00.807182) ========= ========= Total master_lag: 0.16 =========