builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0184 starttime: 1460720526.35 results: success (0) buildid: 20160415024251 builduid: cdad4b5acff4434895d22c2f73127b4a revision: afd82f887093e5e9e4015115ca5795ec82a6f732 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:06.349693) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:06.350128) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:06.350400) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:06.374389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:06.374770) ========= 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.0mikMVyVDV/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.IUAzbdGRmC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-04-15 04:42:06-- 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% 28.7M=0s 2016-04-15 04:42:06 (28.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102317 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:06.638282) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 04:42:06.638694) ========= 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.0mikMVyVDV/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.IUAzbdGRmC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.034507 ========= master_lag: 1.15 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 04:42:07.820469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 04:42:07.820840) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev afd82f887093e5e9e4015115ca5795ec82a6f732 --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 afd82f887093e5e9e4015115ca5795ec82a6f732 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0mikMVyVDV/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.IUAzbdGRmC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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-04-15 04:42:07,992 truncating revision to first 12 chars 2016-04-15 04:42:07,992 Setting DEBUG logging. 2016-04-15 04:42:07,992 attempt 1/10 2016-04-15 04:42:07,993 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/afd82f887093?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 04:42:08,634 unpacking tar archive at: mozilla-central-afd82f887093/testing/mozharness/ program finished with exit code 0 elapsedTime=0.889538 ========= master_lag: 0.50 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 04:42:09.205826) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:09.206136) ========= script_repo_revision: afd82f887093e5e9e4015115ca5795ec82a6f732 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:09.206525) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:09.206801) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 04:42:09.282300) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-04-15 04:42:09.282838) ========= /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.0mikMVyVDV/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.IUAzbdGRmC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:09 INFO - MultiFileLogger online at 20160415 04:42:09 in /builds/slave/test 04:42:09 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 04:42:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:42:09 INFO - {'append_to_log': False, 04:42:09 INFO - 'base_work_dir': '/builds/slave/test', 04:42:09 INFO - 'blob_upload_branch': 'Firefox', 04:42:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:42:09 INFO - 'branch': 'Firefox', 04:42:09 INFO - 'buildbot_json_path': 'buildprops.json', 04:42:09 INFO - 'config_files': ('talos/mac_config.py',), 04:42:09 INFO - 'default_actions': ('clobber', 04:42:09 INFO - 'read-buildbot-config', 04:42:09 INFO - 'download-and-extract', 04:42:09 INFO - 'populate-webroot', 04:42:09 INFO - 'create-virtualenv', 04:42:09 INFO - 'install', 04:42:09 INFO - 'run-tests'), 04:42:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:42:09 INFO - 'download_minidump_stackwalk': True, 04:42:09 INFO - 'download_symbols': 'ondemand', 04:42:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:42:09 INFO - 'tooltool.py': '/tools/tooltool.py', 04:42:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:42:09 INFO - '/tools/misc-python/virtualenv.py')}, 04:42:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:42:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:42:09 INFO - 'installer_path': 'installer.exe', 04:42:09 INFO - 'log_level': 'info', 04:42:09 INFO - 'log_name': 'talos', 04:42:09 INFO - 'log_to_console': True, 04:42:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:42:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:42:09 INFO - 'opt_config_files': (), 04:42:09 INFO - 'pip_index': False, 04:42:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:09 INFO - 'cmd': ('bash', 04:42:09 INFO - '-c', 04:42:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:09 INFO - 'enabled': True, 04:42:09 INFO - 'halt_on_failure': False, 04:42:09 INFO - 'name': 'check_screen_resolution'},), 04:42:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:42:09 INFO - 'cmd': ('bash', 04:42:09 INFO - '-c', 04:42:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:42:09 INFO - 'enabled': True, 04:42:09 INFO - 'halt_on_failure': False, 04:42:09 INFO - 'name': 'check_screen_resolution'},), 04:42:09 INFO - 'run_cmd_checks_enabled': True, 04:42:09 INFO - 'sps_profile': False, 04:42:09 INFO - 'sps_profile_interval': 0, 04:42:09 INFO - 'suite': 'dromaeojs-e10s', 04:42:09 INFO - 'system_bits': '32', 04:42:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:42:09 INFO - 'title': 't-yosemite-r7-0184', 04:42:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:42:09 INFO - 'use_talos_json': True, 04:42:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:42:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:42:09 INFO - 'work_dir': 'build'} 04:42:09 INFO - ##### 04:42:09 INFO - ##### Running clobber step. 04:42:09 INFO - ##### 04:42:09 INFO - Running pre-action listener: _resource_record_pre_action 04:42:09 INFO - Running main action method: clobber 04:42:09 INFO - rmtree: /builds/slave/test/build 04:42:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:42:10 INFO - Running post-action listener: _resource_record_post_action 04:42:10 INFO - ##### 04:42:10 INFO - ##### Running read-buildbot-config step. 04:42:10 INFO - ##### 04:42:10 INFO - Running pre-action listener: _resource_record_pre_action 04:42:10 INFO - Running main action method: read_buildbot_config 04:42:10 INFO - Using buildbot properties: 04:42:10 INFO - { 04:42:10 INFO - "project": "", 04:42:10 INFO - "product": "firefox", 04:42:10 INFO - "script_repo_revision": "production", 04:42:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:42:10 INFO - "repository": "", 04:42:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:42:10 INFO - "buildid": "20160415024251", 04:42:10 INFO - "pgo_build": "False", 04:42:10 INFO - "basedir": "/builds/slave/test", 04:42:10 INFO - "buildnumber": 188, 04:42:10 INFO - "slavename": "t-yosemite-r7-0184", 04:42:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:42:10 INFO - "platform": "yosemite_r7", 04:42:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:42:10 INFO - "branch": "mozilla-central", 04:42:10 INFO - "repo_path": "mozilla-central", 04:42:10 INFO - "revision": "afd82f887093e5e9e4015115ca5795ec82a6f732", 04:42:10 INFO - "stage_platform": "macosx64", 04:42:10 INFO - "builduid": "cdad4b5acff4434895d22c2f73127b4a", 04:42:10 INFO - "slavebuilddir": "test" 04:42:10 INFO - } 04:42:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:42:10 INFO - Running post-action listener: _resource_record_post_action 04:42:10 INFO - ##### 04:42:10 INFO - ##### Running download-and-extract step. 04:42:10 INFO - ##### 04:42:10 INFO - Running pre-action listener: _resource_record_pre_action 04:42:10 INFO - Running main action method: download_and_extract 04:42:10 INFO - mkdir: /builds/slave/test/build/tests 04:42:10 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')]} 04:42:11 INFO - https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:42:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:42:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:42:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:42:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:42:12 INFO - Downloaded 1199 bytes. 04:42:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:42:12 INFO - Using the following test package requirements: 04:42:12 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:42:12 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:42:12 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:42:12 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'jsshell-mac.zip'], 04:42:12 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:42:12 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:42:12 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:42:12 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:42:12 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:42:12 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:42:12 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:42:12 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:42: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')]} 04:42:12 INFO - https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:42:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:16 INFO - Downloaded 18033981 bytes. 04:42:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:42:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:42:19 INFO - Return code: 0 04:42:19 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:42: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')]} 04:42:19 INFO - https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:42:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:42:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:20 INFO - Downloaded 18033981 bytes. 04:42:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:42:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:42:24 INFO - Return code: 0 04:42: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')]} 04:42:24 INFO - https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:42:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:42:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:42:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:42:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:42:25 INFO - Downloaded 10841076 bytes. 04:42:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:42:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:42:25 INFO - Return code: 0 04:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:25 INFO - https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:42:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:42:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:42:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:42:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:42:29 INFO - Downloaded 109927262 bytes. 04:42:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:42:29 INFO - mkdir: /builds/slave/test/properties 04:42:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:42:29 INFO - Writing to file /builds/slave/test/properties/build_url 04:42:29 INFO - Contents: 04:42:29 INFO - build_url:https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:42:31 INFO - Running post-action listener: _resource_record_post_action 04:42:31 INFO - Running post-action listener: set_extra_try_arguments 04:42:31 INFO - ##### 04:42:31 INFO - ##### Running populate-webroot step. 04:42:31 INFO - ##### 04:42:31 INFO - Running pre-action listener: _resource_record_pre_action 04:42:31 INFO - Running main action method: populate_webroot 04:42:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:42:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:42:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:42:31 INFO - '--tppagecycles', 04:42:31 INFO - '7'], 04:42:31 INFO - 'tests': ['tsvgm']}}, 04:42:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:42:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:42:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:42:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:42:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['damp', 'tps']}, 04:42:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'tests': ['damp', 'tps']}, 04:42:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['dromaeo_dom']}, 04:42:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:42:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['a11yr', 04:42:31 INFO - 'ts_paint', 04:42:31 INFO - 'tpaint', 04:42:31 INFO - 'sessionrestore', 04:42:31 INFO - 'sessionrestore_no_auto_restore', 04:42:31 INFO - 'tabpaint']}, 04:42:31 INFO - 'other-e10s': {'tests': ['a11yr', 04:42:31 INFO - 'ts_paint', 04:42:31 INFO - 'tpaint', 04:42:31 INFO - 'sessionrestore', 04:42:31 INFO - 'sessionrestore_no_auto_restore', 04:42:31 INFO - 'tabpaint']}, 04:42:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:42:31 INFO - 'ts_paint', 04:42:31 INFO - 'tpaint', 04:42:31 INFO - 'sessionrestore', 04:42:31 INFO - 'sessionrestore_no_auto_restore', 04:42:31 INFO - 'tabpaint']}, 04:42:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:42:31 INFO - 'ts_paint', 04:42:31 INFO - 'tpaint', 04:42:31 INFO - 'sessionrestore', 04:42:31 INFO - 'sessionrestore_no_auto_restore', 04:42:31 INFO - 'tabpaint']}, 04:42:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['a11yr', 04:42:31 INFO - 'ts_paint', 04:42:31 INFO - 'tpaint', 04:42:31 INFO - 'sessionrestore', 04:42:31 INFO - 'sessionrestore_no_auto_restore', 04:42:31 INFO - 'tabpaint']}, 04:42:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['a11yr', 04:42:31 INFO - 'ts_paint', 04:42:31 INFO - 'tpaint', 04:42:31 INFO - 'sessionrestore', 04:42:31 INFO - 'sessionrestore_no_auto_restore', 04:42:31 INFO - 'tabpaint']}, 04:42:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['tsvgx', 04:42:31 INFO - 'tsvgr_opacity', 04:42:31 INFO - 'tart', 04:42:31 INFO - 'tscrollx', 04:42:31 INFO - 'cart']}, 04:42:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:42:31 INFO - 'tsvgr_opacity', 04:42:31 INFO - 'tart', 04:42:31 INFO - 'tscrollx', 04:42:31 INFO - 'cart']}, 04:42:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'talos_options': ['--disable-e10s'], 04:42:31 INFO - 'tests': ['tp5o']}, 04:42:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'tests': ['tp5o']}, 04:42:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'talos_options': ['--disable-e10s', 04:42:31 INFO - '--xperf_path', 04:42:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:31 INFO - 'tests': ['tp5n']}, 04:42:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:42:31 INFO - 'talos_options': ['--xperf_path', 04:42:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:31 INFO - 'tests': ['tp5n']}}, 04:42:31 INFO - 'talos.zip': {'path': '', 04:42:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:42:31 INFO - Running post-action listener: _resource_record_post_action 04:42:31 INFO - ##### 04:42:31 INFO - ##### Running create-virtualenv step. 04:42:31 INFO - ##### 04:42:31 INFO - Running pre-action listener: _resource_record_pre_action 04:42:31 INFO - Running main action method: create_virtualenv 04:42:31 INFO - Creating virtualenv /builds/slave/test/build/venv 04:42:31 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 04:42:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:42:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:42:31 INFO - Using real prefix '/tools/python27' 04:42:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:42:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:42:34 INFO - Installing pip.................done. 04:42:34 INFO - Return code: 0 04:42:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42: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', 'pip>=1.5'] in /builds/slave/test/build 04:42: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 pip>=1.5 04:42:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:34 INFO - 'HOME': '/Users/cltbld', 04:42:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:34 INFO - 'LOGNAME': 'cltbld', 04:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:34 INFO - 'MOZ_NO_REMOTE': '1', 04:42:34 INFO - 'NO_EM_RESTART': '1', 04:42:34 INFO - 'PAGER': '/bin/cat', 04:42:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:34 INFO - 'PWD': '/builds/slave/test', 04:42:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:34 INFO - 'SHELL': '/bin/bash', 04:42:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:34 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:34 INFO - 'USER': 'cltbld', 04:42:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:34 INFO - 'XPC_FLAGS': '0x0', 04:42:34 INFO - 'XPC_SERVICE_NAME': '0', 04:42:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:35 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 04:42:35 INFO - Cleaning up... 04:42:35 INFO - Return code: 0 04:42:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:42:35 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')]} 04:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:35 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')]} 04:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:35 INFO - 'HOME': '/Users/cltbld', 04:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:35 INFO - 'LOGNAME': 'cltbld', 04:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:35 INFO - 'MOZ_NO_REMOTE': '1', 04:42:35 INFO - 'NO_EM_RESTART': '1', 04:42:35 INFO - 'PAGER': '/bin/cat', 04:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:35 INFO - 'PWD': '/builds/slave/test', 04:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:35 INFO - 'SHELL': '/bin/bash', 04:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:35 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:35 INFO - 'USER': 'cltbld', 04:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:35 INFO - 'XPC_FLAGS': '0x0', 04:42:35 INFO - 'XPC_SERVICE_NAME': '0', 04:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:35 INFO - Downloading/unpacking psutil>=0.7.1 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:42:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:38 INFO - Installing collected packages: psutil 04:42:38 INFO - Running setup.py install for psutil 04:42:38 INFO - building 'psutil._psutil_osx' extension 04:42:38 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 04:42:38 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 04:42:38 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 04:42:38 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 04:42:38 INFO - building 'psutil._psutil_posix' extension 04:42:38 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 04:42:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:42:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:42:39 INFO - ^ 04:42:39 INFO - 1 warning generated. 04:42:39 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 04:42:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:39 INFO - Successfully installed psutil 04:42:39 INFO - Cleaning up... 04:42:39 INFO - Return code: 0 04:42:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:42:39 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')]} 04:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:39 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')]} 04:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:39 INFO - 'HOME': '/Users/cltbld', 04:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:39 INFO - 'LOGNAME': 'cltbld', 04:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:39 INFO - 'MOZ_NO_REMOTE': '1', 04:42:39 INFO - 'NO_EM_RESTART': '1', 04:42:39 INFO - 'PAGER': '/bin/cat', 04:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:39 INFO - 'PWD': '/builds/slave/test', 04:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:39 INFO - 'SHELL': '/bin/bash', 04:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:39 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:39 INFO - 'USER': 'cltbld', 04:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:39 INFO - 'XPC_FLAGS': '0x0', 04:42:39 INFO - 'XPC_SERVICE_NAME': '0', 04:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:42:39 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 04:42:39 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 04:42:39 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 04:42:39 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 04:42:39 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 04:42:39 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 04:42:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:42:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:42:42 INFO - Installing collected packages: mozsystemmonitor 04:42:42 INFO - Running setup.py install for mozsystemmonitor 04:42:42 INFO - Successfully installed mozsystemmonitor 04:42:42 INFO - Cleaning up... 04:42:42 INFO - Return code: 0 04:42:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:42 INFO - 'HOME': '/Users/cltbld', 04:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:42 INFO - 'LOGNAME': 'cltbld', 04:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:42 INFO - 'MOZ_NO_REMOTE': '1', 04:42:42 INFO - 'NO_EM_RESTART': '1', 04:42:42 INFO - 'PAGER': '/bin/cat', 04:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:42 INFO - 'PWD': '/builds/slave/test', 04:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:42 INFO - 'SHELL': '/bin/bash', 04:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:42 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:42 INFO - 'USER': 'cltbld', 04:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:42 INFO - 'XPC_FLAGS': '0x0', 04:42:42 INFO - 'XPC_SERVICE_NAME': '0', 04:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:42 INFO - Downloading/unpacking blobuploader==1.2.4 04:42:42 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 04:42:42 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 04:42:42 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 04:42:42 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 04:42:42 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 04:42:42 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 04:42:44 INFO - Downloading blobuploader-1.2.4.tar.gz 04:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:42:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:42:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:46 INFO - Downloading docopt-0.6.1.tar.gz 04:42:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:42:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:42:46 INFO - Running setup.py install for blobuploader 04:42:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:42:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:42:46 INFO - Running setup.py install for requests 04:42:46 INFO - Running setup.py install for docopt 04:42:47 INFO - Successfully installed blobuploader requests docopt 04:42:47 INFO - Cleaning up... 04:42:47 INFO - Return code: 0 04:42:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:47 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 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:47 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 04:42:47 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 04:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:47 INFO - 'HOME': '/Users/cltbld', 04:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:47 INFO - 'LOGNAME': 'cltbld', 04:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:47 INFO - 'MOZ_NO_REMOTE': '1', 04:42:47 INFO - 'NO_EM_RESTART': '1', 04:42:47 INFO - 'PAGER': '/bin/cat', 04:42:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:47 INFO - 'PWD': '/builds/slave/test', 04:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:47 INFO - 'SHELL': '/bin/bash', 04:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:47 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:47 INFO - 'USER': 'cltbld', 04:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:47 INFO - 'XPC_FLAGS': '0x0', 04:42:47 INFO - 'XPC_SERVICE_NAME': '0', 04:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-SJmtib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-gb0Qof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-RjQKsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-iU3bWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-wGA3WH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:47 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-7gK3zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-KsOD0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-IcmKtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-gDzxNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-CvDznu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-nZeiZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-E9QSks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-PPMaQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-QgGr4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-eJu_tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:48 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ULdwbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:49 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-yRlE8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:42:49 INFO - Running setup.py install for manifestparser 04:42:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:42:49 INFO - Running setup.py install for mozcrash 04:42:49 INFO - Running setup.py install for mozdebug 04:42:49 INFO - Running setup.py install for mozdevice 04:42:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:42:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:42:49 INFO - Running setup.py install for mozfile 04:42:49 INFO - Running setup.py install for mozhttpd 04:42:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Running setup.py install for mozinfo 04:42:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Running setup.py install for mozInstall 04:42:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Running setup.py install for mozleak 04:42:50 INFO - Running setup.py install for mozlog 04:42:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Running setup.py install for moznetwork 04:42:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Running setup.py install for mozprocess 04:42:50 INFO - Running setup.py install for mozprofile 04:42:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:42:50 INFO - Running setup.py install for mozrunner 04:42:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:42:51 INFO - Running setup.py install for mozscreenshot 04:42:51 INFO - Running setup.py install for moztest 04:42:51 INFO - Running setup.py install for mozversion 04:42:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:42:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:42:51 INFO - Cleaning up... 04:42:51 INFO - Return code: 0 04:42:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:52 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')]} 04:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:52 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 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:52 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 04:42:52 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 04:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:52 INFO - 'HOME': '/Users/cltbld', 04:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:52 INFO - 'LOGNAME': 'cltbld', 04:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:52 INFO - 'MOZ_NO_REMOTE': '1', 04:42:52 INFO - 'NO_EM_RESTART': '1', 04:42:52 INFO - 'PAGER': '/bin/cat', 04:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:52 INFO - 'PWD': '/builds/slave/test', 04:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:52 INFO - 'SHELL': '/bin/bash', 04:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:52 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:52 INFO - 'USER': 'cltbld', 04:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:52 INFO - 'XPC_FLAGS': '0x0', 04:42:52 INFO - 'XPC_SERVICE_NAME': '0', 04:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-CKhKlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:52 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)) 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-OVPmhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:52 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)) 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-PfP9Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:52 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)) 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-cXhizx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:52 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)) 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Ff6MQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:52 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)) 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-1y5maF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:52 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)) 04:42:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:52 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-MMD_48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-R3V8k2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-Cq1MrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-38OLy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-ycxvNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-1gs4xT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-pCWjk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-XysanB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-o9tWGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:53 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)) 04:42:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:53 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-xI_lsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:54 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)) 04:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:54 INFO - Running setup.py (path:/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/pip-_gOeJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:54 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)) 04:42:54 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)) 04:42:54 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)) 04:42:54 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)) 04:42:54 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)) 04:42:54 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)) 04:42:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:56 INFO - Downloading blessings-1.6.tar.gz 04:42:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:42:57 INFO - Installing collected packages: blessings 04:42:57 INFO - Running setup.py install for blessings 04:42:57 INFO - Successfully installed blessings 04:42:57 INFO - Cleaning up... 04:42:57 INFO - Return code: 0 04:42:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:42:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:57 INFO - Reading from file tmpfile_stdout 04:42:57 INFO - Current package versions: 04:42:57 INFO - blessings == 1.6 04:42:57 INFO - blobuploader == 1.2.4 04:42:57 INFO - docopt == 0.6.1 04:42:57 INFO - manifestparser == 1.1 04:42:57 INFO - mozInstall == 1.12 04:42:57 INFO - mozcrash == 0.17 04:42:57 INFO - mozdebug == 0.1 04:42:57 INFO - mozdevice == 0.48 04:42:57 INFO - mozfile == 1.2 04:42:57 INFO - mozhttpd == 0.7 04:42:57 INFO - mozinfo == 0.9 04:42:57 INFO - mozleak == 0.1 04:42:57 INFO - mozlog == 3.1 04:42:57 INFO - moznetwork == 0.27 04:42:57 INFO - mozprocess == 0.22 04:42:57 INFO - mozprofile == 0.28 04:42:57 INFO - mozrunner == 6.11 04:42:57 INFO - mozscreenshot == 0.1 04:42:57 INFO - mozsystemmonitor == 0.0 04:42:57 INFO - moztest == 0.7 04:42:57 INFO - mozversion == 1.4 04:42:57 INFO - psutil == 3.1.1 04:42:57 INFO - requests == 1.2.3 04:42:57 INFO - wsgiref == 0.1.2 04:42:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:57 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 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:57 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 04:42:57 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 04:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:57 INFO - 'HOME': '/Users/cltbld', 04:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:57 INFO - 'LOGNAME': 'cltbld', 04:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:57 INFO - 'MOZ_NO_REMOTE': '1', 04:42:57 INFO - 'NO_EM_RESTART': '1', 04:42:57 INFO - 'PAGER': '/bin/cat', 04:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:57 INFO - 'PWD': '/builds/slave/test', 04:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:57 INFO - 'SHELL': '/bin/bash', 04:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:57 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:57 INFO - 'USER': 'cltbld', 04:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:57 INFO - 'XPC_FLAGS': '0x0', 04:42:57 INFO - 'XPC_SERVICE_NAME': '0', 04:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 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)) 04:42:57 INFO - Cleaning up... 04:42:57 INFO - Return code: 0 04:42:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:42: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')]} 04:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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')]} 04:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:57 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 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:42:57 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 04:42:57 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 04:42:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:42:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:57 INFO - 'HOME': '/Users/cltbld', 04:42:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:57 INFO - 'LOGNAME': 'cltbld', 04:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:57 INFO - 'MOZ_NO_REMOTE': '1', 04:42:57 INFO - 'NO_EM_RESTART': '1', 04:42:57 INFO - 'PAGER': '/bin/cat', 04:42:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:57 INFO - 'PWD': '/builds/slave/test', 04:42:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:57 INFO - 'SHELL': '/bin/bash', 04:42:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:42:57 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:42:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:57 INFO - 'USER': 'cltbld', 04:42:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:57 INFO - 'XPC_FLAGS': '0x0', 04:42:57 INFO - 'XPC_SERVICE_NAME': '0', 04:42:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:58 INFO - Downloading/unpacking jsonschema 04:42:58 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 04:42:58 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 04:42:58 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 04:42:58 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 04:42:58 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 04:42:58 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 04:43:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:43:00 INFO - Downloading/unpacking functools32 (from jsonschema) 04:43:00 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 04:43:00 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 04:43:00 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 04:43:00 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 04:43:00 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 04:43:00 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 04:43:00 INFO - Downloading functools32-3.2.3-2.tar.gz 04:43:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:43:00 INFO - warning: no files found matching '*.txt' 04:43:00 INFO - no previously-included directories found matching 'build' 04:43:00 INFO - no previously-included directories found matching 'dist' 04:43:00 INFO - no previously-included directories found matching '.git*' 04:43:00 INFO - Installing collected packages: jsonschema, functools32 04:43:01 INFO - Running setup.py install for functools32 04:43:01 INFO - warning: no files found matching '*.txt' 04:43:01 INFO - no previously-included directories found matching 'build' 04:43:01 INFO - no previously-included directories found matching 'dist' 04:43:01 INFO - no previously-included directories found matching '.git*' 04:43:01 INFO - Successfully installed jsonschema functools32 04:43:01 INFO - Cleaning up... 04:43:01 INFO - Return code: 0 04:43:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:43:01 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')]} 04:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:01 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')]} 04:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:01 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 0x10fe2aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe3a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa052d6f7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, '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.0mikMVyVDV/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.IUAzbdGRmC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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 04:43:01 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 04:43:01 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 04:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:01 INFO - 'HOME': '/Users/cltbld', 04:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:01 INFO - 'LOGNAME': 'cltbld', 04:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:01 INFO - 'MOZ_NO_REMOTE': '1', 04:43:01 INFO - 'NO_EM_RESTART': '1', 04:43:01 INFO - 'PAGER': '/bin/cat', 04:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:01 INFO - 'PWD': '/builds/slave/test', 04:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:01 INFO - 'SHELL': '/bin/bash', 04:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:43:01 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:01 INFO - 'USER': 'cltbld', 04:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:01 INFO - 'XPC_FLAGS': '0x0', 04:43:01 INFO - 'XPC_SERVICE_NAME': '0', 04:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:01 INFO - Downloading/unpacking flake8 04:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:43:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:43:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:04 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 04:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:43:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:43:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:43:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:43:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:43:05 INFO - no previously-included directories found matching 'docs/_build' 04:43:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:43:05 INFO - Running setup.py install for pep8 04:43:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:43:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:43:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:43:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:43:05 INFO - no previously-included directories found matching 'docs/_build' 04:43:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:43:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:43:05 INFO - Cleaning up... 04:43:05 INFO - Return code: 0 04:43:05 INFO - Running post-action listener: _resource_record_post_action 04:43:05 INFO - Running post-action listener: _start_resource_monitoring 04:43:05 INFO - Starting resource monitoring. 04:43:05 INFO - ##### 04:43:05 INFO - ##### Running install step. 04:43:05 INFO - ##### 04:43:05 INFO - Running pre-action listener: _resource_record_pre_action 04:43:05 INFO - Running main action method: install 04:43:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:43:05 INFO - Reading from file tmpfile_stdout 04:43:05 INFO - Detecting whether we're running mozinstall >=1.0... 04:43:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:43:06 INFO - Reading from file tmpfile_stdout 04:43:06 INFO - Output received: 04:43:06 INFO - Usage: mozinstall [options] installer 04:43:06 INFO - Options: 04:43:06 INFO - -h, --help show this help message and exit 04:43:06 INFO - -d DEST, --destination=DEST 04:43:06 INFO - Directory to install application into. [default: 04:43:06 INFO - "/builds/slave/test"] 04:43:06 INFO - --app=APP Application being installed. [default: firefox] 04:43:06 INFO - mkdir: /builds/slave/test/build/application 04:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:43:39 INFO - Reading from file tmpfile_stdout 04:43:39 INFO - Output received: 04:43:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:43:39 INFO - Running post-action listener: _resource_record_post_action 04:43:39 INFO - ##### 04:43:39 INFO - ##### Running run-tests step. 04:43:39 INFO - ##### 04:43:39 INFO - Running pre-action listener: _resource_record_pre_action 04:43:39 INFO - Running main action method: run_tests 04:43:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:39 INFO - 2016-04-15 04:43:39.498 screenresolution[1642:7488] starting screenresolution argv=screenresolution get 04:43:39 INFO - 2016-04-15 04:43:39.500 screenresolution[1642:7488] Display 0: 1600x1200x32@60 04:43:39 INFO - 2016-04-15 04:43:39.506 screenresolution[1643:7490] starting screenresolution argv=screenresolution list 04:43:39 INFO - Available Modes on Display 0 04:43:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:43:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:43:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:43:39 INFO - Intel Iris: 04:43:39 INFO - Chipset Model: Intel Iris 04:43:39 INFO - Type: GPU 04:43:39 INFO - Bus: Built-In 04:43:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:39 INFO - Vendor: Intel (0x8086) 04:43:39 INFO - Device ID: 0x0a2e 04:43:39 INFO - Revision ID: 0x0009 04:43:39 INFO - Displays: 04:43:39 INFO - Display: 04:43:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:43:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:39 INFO - Display Serial Number: 1600x1200 60 04:43:39 INFO - Main Display: Yes 04:43:39 INFO - Mirror: Off 04:43:39 INFO - Online: Yes 04:43:39 INFO - Rotation: Supported 04:43:39 INFO - Return code: 0 04:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:43:39 INFO - Python 2.7.3 04:43:39 INFO - Return code: 0 04:43:39 INFO - grabbing minidump binary from tooltool 04:43:39 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')]} 04:43:39 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc9e00>, '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 0x10fb32db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fcc4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:43:39 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 04:43:39 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 04:43:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:43:39 INFO - Return code: 0 04:43:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:43:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:43:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:43:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:43:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:43:41 INFO - Return code: 0 04:43:41 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/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--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 04:43:41 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/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0184 --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 04:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mikMVyVDV/Render', 04:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:41 INFO - 'HOME': '/Users/cltbld', 04:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:41 INFO - 'LOGNAME': 'cltbld', 04:43:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:43:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:41 INFO - 'MOZ_NO_REMOTE': '1', 04:43:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:43:41 INFO - 'NO_EM_RESTART': '1', 04:43:41 INFO - 'PAGER': '/bin/cat', 04:43:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:41 INFO - 'PWD': '/builds/slave/test', 04:43:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:41 INFO - 'SHELL': '/bin/bash', 04:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IUAzbdGRmC/Listeners', 04:43:41 INFO - 'TMPDIR': '/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/', 04:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:41 INFO - 'USER': 'cltbld', 04:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:41 INFO - 'XPC_FLAGS': '0x0', 04:43:41 INFO - 'XPC_SERVICE_NAME': '0', 04:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:41 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/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0184', '--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 04:43:41 INFO - mozversion application_buildid: 20160415024251 04:43:41 INFO - mozversion application_changeset: afd82f887093e5e9e4015115ca5795ec82a6f732 04:43:41 INFO - mozversion application_display_name: Nightly 04:43:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:43:41 INFO - mozversion application_name: Firefox 04:43:41 INFO - mozversion application_remotingname: firefox 04:43:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:43:41 INFO - mozversion application_vendor: Mozilla 04:43:41 INFO - mozversion application_version: 48.0a1 04:43:41 INFO - mozversion platform_buildid: 20160415024251 04:43:41 INFO - mozversion platform_changeset: afd82f887093e5e9e4015115ca5795ec82a6f732 04:43:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:43:41 INFO - mozversion platform_version: 48.0a1 04:43:41 INFO - using testdate: 1460720621 04:43:41 INFO - actual date: 1460720621 04:43:41 INFO - starting webserver on 'localhost:49270' 04:43:41 INFO - SUITE-START | Running 2 tests 04:43:41 INFO - TEST-START | dromaeo_css 04:43:41 INFO - operating with platform_type : mac_ 04:43:41 INFO - Initialising browser for dromaeo_css test... 04:43:41 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpeir_7s/profile http://localhost:49270/getInfo.html) 04:43:43 INFO - PROCESS | 1661 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 04:43:44 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 04:43:44 INFO - PROCESS | 1661 | colorDepth:24 04:43:44 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 04:43:44 INFO - PROCESS | 1661 | __metrics 04:43:44 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:43:44 INFO - TEST-INFO | 1661: exit 0 04:43:44 INFO - Browser initialized. 04:43:44 INFO - Running cycle 1/1 for dromaeo_css test... 04:43:44 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmpeir_7s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:56 INFO - PROCESS | 1664 | RSS: Main: 248868864 04:43:56 INFO - PROCESS | 1664 | 04:44:46 INFO - PROCESS | 1664 | 1460720686533 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] 04:46:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:46:59 INFO - PROCESS | 1664 | RSS: Main: 259969024 04:46:59 INFO - PROCESS | 1664 | 04:50:01 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:50:02 INFO - PROCESS | 1664 | RSS: Main: 271986688 04:50:02 INFO - PROCESS | 1664 | 04:53:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:53:04 INFO - PROCESS | 1664 | RSS: Main: 274104320 04:53:04 INFO - PROCESS | 1664 | 04:56:06 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 04:56:07 INFO - PROCESS | 1664 | RSS: Main: 275361792 04:56:07 INFO - PROCESS | 1664 | 04:59:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 04:59:10 INFO - PROCESS | 1664 | RSS: Main: 278650880 04:59:10 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:02:13 INFO - PROCESS | 1664 | RSS: Main: 279728128 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | __start_tp_report 05:02:13 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 05:02:13 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 05:02:13 INFO - PROCESS | 1664 | |i|pagename|runs| 05:02:13 INFO - PROCESS | 1664 | |0;dojo.html;3368.4442067058935;3469.219425629233;3512.718982481402;3533.046077559059;3549.432090865462;5807.47732704056;5808.041673123935;5826.533877289818;5834.445727655871;5894.970525147379;5762.279715035621;5875.735591898364;5924.88150236995;5968.164456976016;6037.2151620289305;5288.312519372476;5331.466853314667;5353.839384818461;5378.8924221515545;5406.567474602022;4559.954400455986;5386.973065134669;5393.541548968343;5397;5428.809991650293;5242.2136679498;5337.279467271907;5351;5395.946040539584;5426.782928682848;5285.286486324336;5285.894282114375;5296.92054619181;5301.628885977983;5346.625736198468;5263.973680131613;5287.048331300364;5320.042392369372;5380.596455265871;5390.272313237702;5737.08206686928;5749.936261791582;5780.2196703444915;5837.036888913324;5889.381614930457;5223.73881305937;6127.325994140641;6180.84547886302;6189.6286222826775;6207.131001659771;5095.974520127413;5129.358830146231;5244.370675518924;6282.465990390822;6324.557280990332;4577.107464044512;5068.391792984866;5660.716964151817;5939.792107276311;5954.583179177503;6307.306196318401;6376.29860715321;6392.4886009119155;6403.359664033559;6408.679566021773;6249.562530622904;6272.749090036439;6280.995040793451;6288.465480434123;6307.96846015767;6180.351063138396;6202.038684003986;6254.562180647402;6282.780102696383;6288.371162883766;6105.52039974972;6203.937960620427;6223.719932603044;6276.152719382916;6294.8426289342215;6179.598326108789;6204.069389591507;6215.844603884939;6245.375462453719;6270.811875643738;4156.625903668684;5012.498750124958;5013.749312534359;5049.065922804293;5051.040302342617;4454;4476.194285028726;4488.169688607618;4501.347304640874;4522.4346956630425;5826.184334193216;5877.353491115974;5877.38287479817;5909.084091965752;5927.525797936155;6192.473639740582;6211.937880621226;6260.186175797117;6274.529410294246;6283.842903927347;5847.239858818326;5890.8821823563285;5893.204417403681;5897.3807750185615;5898.174255604219;5433.185022246695;5449;5472.452754724496;5475.123980163155;5491.835244942658;5813.273340832396;6225.657588832658;6248.4688801452385;6340.048992651139;6433.871322573429;6230;6235.158253635701;6252.374762523711;6264.9976003839165;6284.371562843679;6202.5658203925295;6456.128422663069;6473.546851720334;6478.384553467413;6484.221893372825;6228.096925945803;6254.874902502015;6302.590331628429;6306.716197771203;6318.936810632019;5720.685362305113;5974.671393073423;6221.595596286227;6276.372362763687;6291.433770960635;6105;6147.477464415396;6217;6232.719527621359;6280.403361680633;6089.51283897296;6119.837693858789;6152.723127459186;6221.377862213923;6233.501319894307;4961.627877909099;5949.553783466171;6105.297890742444;6114.755409783559;6114.816555503342;6123.479504242189;6178.165947597017;6187.412195841388;6239.78160764371;6243.469305109116;6099.878002440015;6124.991279909952;6145;6152.98475751496;6176.6911654418 05:02:13 INFO - PROCESS | 1664 | |1;ext.html;498.65841898299647;508.11334221782994;588.1031427073715;601.9488343490804;624.3132554190391;27188.728112718865;38267.234655306915;38287.23425531488;38335.658251961184;38338.233235335276;34354.14114647135;36787.448188277194;39536;39607.405888911686;39630.00924976877;9386.9674335823;9556.331056826026;9567.952160239189;9633.855492167622;9661.323707340489;9570.186534144606;9766.023397660249;9784.657536986208;9788.55951482185;9795.412275263472;9710.417374957526;9715.125638692516;9777.31558790885;9784.755381115494;9795.755106122333;9700.854487182698;9737.221022318197;9758.560864761102;9776.755581110458;9798.216142708638;9464.1009104135;9558.56986435612;9592.376495527767;9609.615615375376;9610.471424071673;37857;37886.81056594727;38340;38492;38511.80744096261;3215.0997720638024;3354.111160542434;3448.6034106077873;3551.449525323579;3566.8038257895805;28273.25610301791;28858.855705721548;28937.855310723524;29705;29771.404571908657;2671.252049426164;2707.5261829180013;2825.0536070416433;2829.39168078862;2833.9716602833914;383126;444881;451611;451658;452690;61123.38876611266;61406;61636.38363616307;61685.38314616886;61703.84036558699;62361.376386236465;63531.682341588;63661.68169159217;63720;63961.36038639554;15871.365145394286;16089.919550402174;16375.263113159697;16407.671846563;16433.753493697604;12297.938510307391;12537.498500059895;12630.747385052247;12714.554990575285;12835.679108022186;15719.764203536728;16172.595685107966;16274.104924229045;16320.93913895593;16322.918385408235;6293.748250069946;7390.9260907390235;7485.962570187223;7539.283768042027;7597.430192735567;979.5543027922421;1000.5497526113278;1006.5923301062935;1013.310948554975;1030.4229631406436;29594.704052959627;29646.66590003455;29713.85143074314;29791;29998;1109.9778004440027;1111.9888801111886;1144.5765066925123;1177.240679761549;1180.3685028509706;11775.88224117765;14154.575362739135;14246.002779805314;14949.775753363709;15075.472358467621;460720.6963965226;716792;735470;736721;738212;57415.71292143512;57533;58313;58430.70784646049;58503.41496585065;1467;1500.7223663622265;1500.917449540264;1501.2493753123438;1509.056839475328;25683.743162568135;29044.854775725988;29080.854595726887;29896.402071958873;29933.700662993964;48464.7576762128;48625;48841;48980;49064.50935490742;489962;493597;493830;493831;496902;25092.498150037263;25446.23661290164;25877.09430169935;25939.74060259373;25957.831897564276;44276;44674.65976020724;45103;45216.54783452123;45396.546034539235;30035.69964300329;31781.20546986344;33582;36881.70914017996;37617.62382376251;36059;36164;36409.45385819162;37062.62937370592;37247.255054899295;35716.46425303674;35772.64227357693;36269.45595816012;36616.0845978842;37281;32067.839660801546;32248.58429504611;32510.024699259062;32613.34773304473;32950.83524582458;24274.63588046216;24395.700116801392;24517.387065322797;24594.38514037092;24606.75393246116 05:02:13 INFO - PROCESS | 1664 | |2;jquery.html;378.5381834162323;382.98851034468976;391.9039835240367;392.0786152541528;399.53454225826914;18585.88484690918;19360.806391936112;19900;20176;20177;19399.709004364944;20034.898080605562;20175.899120504415;20364;20382.082806273716;5644.491995720385;5737.311522617281;5935.169076329317;5939.584229103964;5963.194968679238;5788.18965344852;5805.448482394187;5866.354700982888;5881.117832325142;5913.2608423947;5605.411431799664;5826.359100498942;5827.300723913115;5837.387074357195;5855.326637436707;5696.8860622787315;5746.195532625436;5800.245968024151;5808.535317174616;5820.883582328372;3959.802009899522;5066.6200034997155;5108.846734597968;5121.8463446096675;5129.820456284012;12572.559960401342;12575.874241257561;12830.973522118207;12831.615051548468;12863.099583029192;2004.3786426207923;2120.3744895255936;2262.0951619352227;2300.804431623314;2485.8135639826905;7596.860470929405;9590.232781377474;9631.614735410645;9844.212463002943;12840.61478155657;1469.34614096727;1658.78435803346;1705.4883534939468;1782.3405340024144;1804.5669039430572;82812.58593707053;112140;116473.83526164715;117832;118326;8504.107068757816;8792.824143517222;10576.62981795649;11236.269642473213;11285.492152853141;11507.827382589268;13832.930835345758;13989.580312590639;14009.229492378017;14033.1580105194;6916.896246556305;6933.341332573397;8275.95862020686;8467.618957146944;8492.490450572985;6448.871447496669;8339.708110216114;8358.331333493428;8431.57842107892;8503.447275927045;4189.916201675949;4345.717528360709;4361.5856493633055;4372.657504908113;4410.20616289071;1843.787964384086;1873.325602782997;1896.9945928657833;1899.259288877339;1926.9421917342502;919.3932004876883;933.2394098809449;968.5980318168022;979.5445118020012;1020.1125021231428;18526.536836579195;19645.410637680892;19800.306989255594;19818;19922.003899804953;967.264878692199;971.6842026341467;972.1105188752213;998.7303428074524;1002.6340385759291;7926.48477848938;10190.592376304865;10370.844437333446;10435.00867417594;10600.681979540626;540948;547849;550887;593096;620375;120655.39672301582;126706.74001788707;126967;128072;129786.70213297935;991;992.161623428202;1005.5927349423495;1009.8030883977701;1027.1525991057408;17502.387416440364;17694.203760830536;17722.91138544299;17727.025013623857;18042;47295.52704473049;48841;49607.75196123996;50081.747956301384;50144.74927625339;71626.64186679033;72598;72617.27382726104;73207.63396183198;75285.24714752783;7812.257835505618;7812.921870781447;7912.723054693058;7969.880451793344;8228.382871284797;11206.887931120584;12415.937920310702;12437.129400941847;12532.561360352343;12588.055895808035;10769.084627806724;10837.783244335227;10839;10947.671569852917;10967.725806854893;8918.597564021717;10410.739731506774;10676.412797295912;10772;11047.668569942914;8982.820343593223;10839.458027098772;11126.388048657078;11300.265482743594;11354.205205635526;7727.652255648397;7760.728374506865;7809.477151955314;7967.203279671987;8033.879491807511;8918.821623567623;10740.409277489813;10843.891561084289;10997.670069897917;11009.889901100887 05:02:13 INFO - PROCESS | 1664 | |3;mootools.html;1473.6389584551782;1596.3454983456782;1821.7631707877974;1933.69060950248;1935.341983725533;6960.5823650581;7920.207979202077;8542.316614670826;8813.735587932373;8874.20132188103;8605.655773769042;8622.741317760461;8653.134686531344;8718.956405217965;8735.257503112227;2413.770691784282;2436.2691192642137;2448.338948483908;2452.3010941881566;2460.2742191053594;2427.332483567024;2433.6471211674298;2451.9754802451926;2454.8527088374667;2462.064415522108;2404.8557086574774;2413.734489206186;2446.1438496526252;2446.706395232575;2455.5579995600874;1972.6350625134253;2375;2388.916387926423;2397.700287464067;2418.322869596516;2123.532822778986;2353.670486131943;2360.3509035015336;2401.387646150238;2404.903803847844;8728.432651877607;8766.167214114648;8808.20726134651;8819.735407937773;8831.293496520328;5094.949050509485;5200.97399513;5210.452902445227;5295.417504074549;5300.787968481256;3249.9350012999607;3292.2427841596327;3339.632640409553;3419.8461069251944;3456.9654303457146;969.069693094623;995.0397866059287;995.5271246158089;996.6262651505685;997.4414327976357;11374.14693897961;11497.367644779533;11511;11548.307101573933;11550.884491155064;4217.7891105444605;4553.2469999049945;4591.449026116888;4729.503402142795;4767.189577771787;9618.086281803218;9645.517724113906;9821.754456138653;9831.262655300738;9831.852522212173;5258.868528286747;5273.419923808378;5322.361316642027;5324.680519168863;5380.623356365095;4376.124775045004;4686.554777296152;4850.75746212688;4851.611871050308;4857.975710121497;3906.257811015898;3911.413288006822;3967.861124860616;3995.1210733638554;4077.062275676611;1306.301128896036;1312.527490103562;1326.7412854493475;1333.5199328241824;1336.2383441438287;833.3708050422014;842.4103127810557;894.3918135667808;920.8204400141908;968.6270785747538;5653.180288858175;5684.857878553069;5748.45389687979;5897.262842144732;5955.129991639084;388.56868875548116;399.31716764333316;426.99573004270184;433.2850796186255;455.7197323646;3813.618638136164;3850.133719913053;3850.403187505941;3893.8831835044994;3910.7458015228917;10077.496125193711;10423.843642345371;10553;10558.841617375745;10560.841587376195;10501.9474902625;10528.789424211473;10540.262181647211;10541.947290263499;10572.788544229072;6659.733610655519;7963.721269755531;8141.796455088552;8171.67313307473;8192.467489613156;5613.270274864405;5693.032184528557;5790.334111577054;5829.59192856496;5937.287525496968;9388.57751401175;9731.805363892541;9763.414195148314;9787.804243915225;9932.900670993198;9457.054294570487;9763.609455621696;9905.356151850396;9995.550200241152;10028.348157369748;6577.901331480128;7132.2867713230335;7218.566885986857;7394.2975417335265;7504.061992250969;8145.837083258183;8153.470024448392;8157.388195885451;8232.217939295757;8283.58582070882;5934.881302374015;7039.824004399931;7164.89252661221;7180.964095179491;7409.777706668809;7112.57324560528;7138.250483699137;7256.637168141678;7260.67326970277;7329.377002954809;6886.48351373639;7037.577745335297;7038.436925046087;7045.542039767399;7065.0815393998455;3917.236138952877;4082.4284600155406;4820.566149046603;4821.5178482151505;4985.326980857538;4775.2359622460235;4913.48408417111;4978.8257410990445;5008;5046.47012063728 05:02:13 INFO - PROCESS | 1664 | |4;prototype.html;318.3680394417082;319.1908511922276;325.9820709860957;328.69431428771264;330.4349562248555;5783.739731712072;6196.752129914804;6306.369363063692;6319.241690997087;6320.431161195492;5533.723313834303;6013.338532761392;6168.784092556762;6183.598066125699;6208.534359923013;3949.407588861665;3991.5808840071736;4510.864674059783;4519.457665080195;4569.543045695437;4578.908421831561;4643.098063710181;4663.347131401607;4666.113438446701;4681.555252251031;4020.356742921133;4565.406497155382;4615.723056616597;4617.215073437507;4617.422822147231;4395.648348132142;4425.645948324126;4590.632749380042;4608.792604332813;4619.676622636417;3942.073612701013;3943;3976.343903255988;3987.282289187945;4001.239764444746;4906.533879281463;4931.720405930052;5558;5637.590602349413;5994.910076348859;4918.409790825088;5202.531772140488;5297.8145764898045;5378.1663842104535;5394.514493695587;6504.804855854332;6515.771947981798;6525.510586706016;6541;6543.985682219263;4213.47331583552;4389.814750017509;4393.033532622818;4405.603495685372;4413.867583972454;5969.98510253258;6665.066890635315;6721.495887808385;6873.312668733137;6900.482463815184;3555.6444355564754;4215.515215750228;4292.463442069741;4421.916630425566;4449.755263460476;3987.3819557968563;4095.610916962884;4554.863354099382;4559.156556037077;4561.133384656904;4606.769661516911;5145.588352931756;5182.507661772125;5222.738863056832;5243.8164664236565;5087.491250874883;5979.01346277868;6548.116004339448;6565.441937435276;6617.735290588419;1324.5165514587106;1412.8728414442749;1502.1362716437873;1509.3509790789847;1510.5468359492108;907.732218995398;959.4818797849083;960.7646126698863;984.7784248544164;991.509202944547;6004.159417681528;6024.764923190823;6074.4532992030045;6076.513878889678;6086.665233412116;6566.835829104215;6779.35596118368;6785.219699734495;6818.249992500921;6824.469413233458;5971.701414929236;6012.849678758066;6033.4871535919065;6047.667378294148;6060.454559089703;3533.6643018913164;3542.7520073595115;3564.322778672044;3575.2670702505925;3603.387424137903;5610.242617246619;5836.941630583725;6146.692665366803;6799.456043516507;7005.1944026436595;6376;6859.416949559243;6890.758823441155;6924.515283930177;6966.895496567556;6467.288598254188;6828.398002611372;6917.9308206918295;7046.330598593126;7072.469564782765;5111.488851114858;6141.447269745744;6506.577072490273;6734.528582999143;6806.455483561403;6176.7838125665385;6328.6519241442165;6624.205095388585;6642.169728783902;6714.3957043866285;6444.258910225384;6468.870622587616;6795.728170873307;6941.583504989717;7084.468664850054;3511.1046683095647;3799.8670046548236;4227.3447615619625;4338.696291259708;4397.670174736843;5982.670953097621;6442.8389290268115;6828;6848.623325717133;6886.827829304308;5111.565516930954;5619.80330688424;5632.9436705632415;6622.437092846969;6695.991202639286;5522.5858060644805;6289.9371006291185;6601.53789234749;6612.4710023198995;6688.498362622726;5421.213923980922;6132.233470816148;6353.491720662243;6478.020110749108;6494.415502604788;6157.32269450369;6604.405603495708;6617.43751781104;6626.403623673892;6632.933670663231;5217.973910130425;5682.602217844711;6113.877722445615;6350.809475715736;6521.543491955707 05:02:13 INFO - PROCESS | 1664 | |5;yui.html;671.946244300456;672.5493919074223;698.291234397087;726.2265687043297;773.1495355109383;12362.814557781641;14330.71023607875;15025.32386042628;15099.698006039873;15139.545813625595;15354.309056092474;15977.041377517357;16024;16037.518874433756;16073;722.6567380494249;902.8104098139365;903.7921278106039;910.512875611548;911.7902882337033;709.5458906299972;845.2266176448572;920.6087412849546;925.0841666749902;927.9211267042309;938.7934654376061;970.8009857979133;978.5107446276861;980.9313348065639;981.1464026297132;931.725141083382;934.0192797562532;934.5887809363892;951.9952400238024;969.4038166527605;690.2062627977815;703.1772825793782;723.5586292361655;724.5037149552521;724.9637518124073;15273.236338183158;15456.768148477782;15545.844541554552;15552.300146493322;15568.299426525833;12414.131010829245;12442.37788110591;12465.314407707572;12494.187877787916;12671.809922851164;9963.011810438005;9976.251781116445;10402.791944161074;10621.309614875008;10762.408067556282;3461.117415059169;3495.7727747696317;3496.720262379029;3575.982120089409;3595.928081438383;81136;82152.58923705402;82343.5882820588;82487.5875620624;82514.58742706307;9353.298502612332;9364.391314564531;9377.671781487616;9614.615415383447;9631.903680963242;20888;20972.79027209739;21028;21185.470363240733;21327.89336053341;7288.599127048064;7390.5196162249285;7471.813204669927;7512.361449276873;7638.815983522563;4038.434619153321;4079.6124368184974;4089.182163567239;4090.2433049885867;4123.154753275571;2839.801213915006;3539.557555305587;3559.5728512578658;3563.982180089135;3566.32239874423;604.6160687963174;605.4732382826969;607.3501353551745;621.9844503887439;623.8939380305359;546.7539607176786;597.9595503823404;601.0803470689851;609.5489337890018;612.1705089603612;7139.607321597258;7650.923490765133;7777.066751989684;7944.483608565425;8055.113937466874;506.6073792810601;520.3001962360619;534.0147427995342;558.1794761700376;595.7468076067654;3698.981505092457;3792.924141517154;3834.2523207974177;3867.845286188577;3896.6103389661375;91704.54147729352;91888.08111918929;93762;94359.52820235855;94822;78193.60903195447;78325.04187395361;78488.60755696299;78555.82166267511;78614.606926965;56800.71599642058;61234.693826531475;67673;68464.89210647377;70342.53801116937;7450.0687414073245;7453.701851925862;7548.320651141204;7593.240675932362;7740.961295193488;39750.91955861145;43111.35332969945;43854.561454385046;44165.77917110394;45845.54154458413;44647;49134.52596422113;55312.72343638256;57963.54299359087;60004.39995600163;9137.725868223964;10779.838302425313;10821.891781082088;10965.725856853642;11241.932016458424;11854.348010859141;11858.347790871585;12039.97660198858;12303.75392492163;12323.630291091282;8983.550822458721;10646.254762166574;11030.393328367018;11464.713382165513;11508.827367589325;11085.334879907232;11228.943855280671;11600.361980091177;11725.061995040547;11778.175527712978;9266.21237194819;11346.602868899548;11387.373694446886;11390.829137563109;11581.478833452684;9248.583813728264;9279.164875161268;9681.080297371738;9706.75733106678;9901;9428.858567121362;10022.837723361228;10038.53315145433;10132.848007280045;10151.289409741543 05:02:13 INFO - PROCESS | 1664 | __end_tp_report 05:02:13 INFO - PROCESS | 1664 | __start_cc_report 05:02:13 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,396 05:02:13 INFO - PROCESS | 1664 | __end_cc_report 05:02:13 INFO - PROCESS | 1664 | __startTimestamp1460721733405__endTimestamp 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | ------- Summary: start ------- 05:02:13 INFO - PROCESS | 1664 | Number of tests: 6 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5776.22 Median:5974.67 stddev:648.09 (10.8%) stddev-sans-first:621.94 05:02:13 INFO - PROCESS | 1664 | Values: 3368.4 3469.2 3512.7 3533.0 3549.4 5807.5 5808.0 5826.5 5834.4 5895.0 5762.3 5875.7 5924.9 5968.2 6037.2 5288.3 5331.5 5353.8 5378.9 5406.6 4560.0 5387.0 5393.5 5397.0 5428.8 5242.2 5337.3 5351.0 5395.9 5426.8 5285.3 5285.9 5296.9 5301.6 5346.6 5264.0 5287.0 5320.0 5380.6 5390.3 5737.1 5749.9 5780.2 5837.0 5889.4 5223.7 6127.3 6180.8 6189.6 6207.1 5096.0 5129.4 5244.4 6282.5 6324.6 4577.1 5068.4 5660.7 5939.8 5954.6 6307.3 6376.3 6392.5 6403.4 6408.7 6249.6 6272.7 6281.0 6288.5 6308.0 6180.4 6202.0 6254.6 6282.8 6288.4 6105.5 6203.9 6223.7 6276.2 6294.8 6179.6 6204.1 6215.8 6245.4 6270.8 4156.6 5012.5 5013.7 5049.1 5051.0 4454.0 4476.2 4488.2 4501.3 4522.4 5826.2 5877.4 5877.4 5909.1 5927.5 6192.5 6211.9 6260.2 6274.5 6283.8 5847.2 5890.9 5893.2 5897.4 5898.2 5433.2 5449.0 5472.5 5475.1 5491.8 5813.3 6225.7 6248.5 6340.0 6433.9 6230.0 6235.2 6252.4 6265.0 6284.4 6202.6 6456.1 6473.5 6478.4 6484.2 6228.1 6254.9 6302.6 6306.7 6318.9 5720.7 5974.7 6221.6 6276.4 6291.4 6105.0 6147.5 6217.0 6232.7 6280.4 6089.5 6119.8 6152.7 6221.4 6233.5 4961.6 5949.6 6105.3 6114.8 6114.8 6123.5 6178.2 6187.4 6239.8 6243.5 6099.9 6125.0 6145.0 6153.0 6176.7 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:66644.69 Median:37449.31 stddev:147242.81 (393.2%) stddev-sans-first:147572.11 05:02:13 INFO - PROCESS | 1664 | Values: 498.7 508.1 588.1 601.9 624.3 27188.7 38267.2 38287.2 38335.7 38338.2 34354.1 36787.4 39536.0 39607.4 39630.0 9387.0 9556.3 9568.0 9633.9 9661.3 9570.2 9766.0 9784.7 9788.6 9795.4 9710.4 9715.1 9777.3 9784.8 9795.8 9700.9 9737.2 9758.6 9776.8 9798.2 9464.1 9558.6 9592.4 9609.6 9610.5 37857.0 37886.8 38340.0 38492.0 38511.8 3215.1 3354.1 3448.6 3551.4 3566.8 28273.3 28858.9 28937.9 29705.0 29771.4 2671.3 2707.5 2825.1 2829.4 2834.0 383126.0 444881.0 451611.0 451658.0 452690.0 61123.4 61406.0 61636.4 61685.4 61703.8 62361.4 63531.7 63661.7 63720.0 63961.4 15871.4 16089.9 16375.3 16407.7 16433.8 12297.9 12537.5 12630.7 12714.6 12835.7 15719.8 16172.6 16274.1 16320.9 16322.9 6293.7 7390.9 7486.0 7539.3 7597.4 979.6 1000.5 1006.6 1013.3 1030.4 29594.7 29646.7 29713.9 29791.0 29998.0 1110.0 1112.0 1144.6 1177.2 1180.4 11775.9 14154.6 14246.0 14949.8 15075.5 460720.7 716792.0 735470.0 736721.0 738212.0 57415.7 57533.0 58313.0 58430.7 58503.4 1467.0 1500.7 1500.9 1501.2 1509.1 25683.7 29044.9 29080.9 29896.4 29933.7 48464.8 48625.0 48841.0 48980.0 49064.5 489962.0 493597.0 493830.0 493831.0 496902.0 25092.5 25446.2 25877.1 25939.7 25957.8 44276.0 44674.7 45103.0 45216.5 45396.5 30035.7 31781.2 33582.0 36881.7 37617.6 36059.0 36164.0 36409.5 37062.6 37247.3 35716.5 35772.6 36269.5 36616.1 37281.0 32067.8 32248.6 32510.0 32613.3 32950.8 24274.6 24395.7 24517.4 24594.4 24606.8 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33052.49 Median:387.45 stddev:95478.76 (24643.1%) stddev-sans-first:95715.08 05:02:13 INFO - PROCESS | 1664 | Values: 378.5 383.0 391.9 392.1 399.5 18585.9 19360.8 19900.0 20176.0 20177.0 19399.7 20034.9 20175.9 20364.0 20382.1 5644.5 5737.3 5935.2 5939.6 5963.2 5788.2 5805.4 5866.4 5881.1 5913.3 5605.4 5826.4 5827.3 5837.4 5855.3 5696.9 5746.2 5800.2 5808.5 5820.9 3959.8 5066.6 5108.8 5121.8 5129.8 12572.6 12575.9 12831.0 12831.6 12863.1 2004.4 2120.4 2262.1 2300.8 2485.8 7596.9 9590.2 9631.6 9844.2 12840.6 1469.3 1658.8 1705.5 1782.3 1804.6 82812.6 112140.0 116473.8 117832.0 118326.0 8504.1 8792.8 10576.6 11236.3 11285.5 11507.8 13832.9 13989.6 14009.2 14033.2 6916.9 6933.3 8276.0 8467.6 8492.5 6448.9 8339.7 8358.3 8431.6 8503.4 4189.9 4345.7 4361.6 4372.7 4410.2 1843.8 1873.3 1897.0 1899.3 1926.9 919.4 933.2 968.6 979.5 1020.1 18526.5 19645.4 19800.3 19818.0 19922.0 967.3 971.7 972.1 998.7 1002.6 7926.5 10190.6 10370.8 10435.0 10600.7 540948.0 547849.0 550887.0 593096.0 620375.0 120655.4 126706.7 126967.0 128072.0 129786.7 991.0 992.2 1005.6 1009.8 1027.2 17502.4 17694.2 17722.9 17727.0 18042.0 47295.5 48841.0 49607.8 50081.7 50144.7 71626.6 72598.0 72617.3 73207.6 75285.2 7812.3 7812.9 7912.7 7969.9 8228.4 11206.9 12415.9 12437.1 12532.6 12588.1 10769.1 10837.8 10839.0 10947.7 10967.7 8918.6 10410.7 10676.4 10772.0 11047.7 8982.8 10839.5 11126.4 11300.3 11354.2 7727.7 7760.7 7809.5 7967.2 8033.9 8918.8 10740.4 10843.9 10997.7 11009.9 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5588.91 Median:5070.71 stddev:3138.50 (61.9%) stddev-sans-first:3132.07 05:02:13 INFO - PROCESS | 1664 | Values: 1473.6 1596.3 1821.8 1933.7 1935.3 6960.6 7920.2 8542.3 8813.7 8874.2 8605.7 8622.7 8653.1 8719.0 8735.3 2413.8 2436.3 2448.3 2452.3 2460.3 2427.3 2433.6 2452.0 2454.9 2462.1 2404.9 2413.7 2446.1 2446.7 2455.6 1972.6 2375.0 2388.9 2397.7 2418.3 2123.5 2353.7 2360.4 2401.4 2404.9 8728.4 8766.2 8808.2 8819.7 8831.3 5094.9 5201.0 5210.5 5295.4 5300.8 3249.9 3292.2 3339.6 3419.8 3457.0 969.1 995.0 995.5 996.6 997.4 11374.1 11497.4 11511.0 11548.3 11550.9 4217.8 4553.2 4591.4 4729.5 4767.2 9618.1 9645.5 9821.8 9831.3 9831.9 5258.9 5273.4 5322.4 5324.7 5380.6 4376.1 4686.6 4850.8 4851.6 4858.0 3906.3 3911.4 3967.9 3995.1 4077.1 1306.3 1312.5 1326.7 1333.5 1336.2 833.4 842.4 894.4 920.8 968.6 5653.2 5684.9 5748.5 5897.3 5955.1 388.6 399.3 427.0 433.3 455.7 3813.6 3850.1 3850.4 3893.9 3910.7 10077.5 10423.8 10553.0 10558.8 10560.8 10501.9 10528.8 10540.3 10541.9 10572.8 6659.7 7963.7 8141.8 8171.7 8192.5 5613.3 5693.0 5790.3 5829.6 5937.3 9388.6 9731.8 9763.4 9787.8 9932.9 9457.1 9763.6 9905.4 9995.6 10028.3 6577.9 7132.3 7218.6 7394.3 7504.1 8145.8 8153.5 8157.4 8232.2 8283.6 5934.9 7039.8 7164.9 7181.0 7409.8 7112.6 7138.3 7256.6 7260.7 7329.4 6886.5 7037.6 7038.4 7045.5 7065.1 3917.2 4082.4 4820.6 4821.5 4985.3 4775.2 4913.5 4978.8 5008.0 5046.5 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5230.19 Median:5988.79 stddev:1655.51 (27.6%) stddev-sans-first:1618.59 05:02:13 INFO - PROCESS | 1664 | Values: 318.4 319.2 326.0 328.7 330.4 5783.7 6196.8 6306.4 6319.2 6320.4 5533.7 6013.3 6168.8 6183.6 6208.5 3949.4 3991.6 4510.9 4519.5 4569.5 4578.9 4643.1 4663.3 4666.1 4681.6 4020.4 4565.4 4615.7 4617.2 4617.4 4395.6 4425.6 4590.6 4608.8 4619.7 3942.1 3943.0 3976.3 3987.3 4001.2 4906.5 4931.7 5558.0 5637.6 5994.9 4918.4 5202.5 5297.8 5378.2 5394.5 6504.8 6515.8 6525.5 6541.0 6544.0 4213.5 4389.8 4393.0 4405.6 4413.9 5970.0 6665.1 6721.5 6873.3 6900.5 3555.6 4215.5 4292.5 4421.9 4449.8 3987.4 4095.6 4554.9 4559.2 4561.1 4606.8 5145.6 5182.5 5222.7 5243.8 5087.5 5979.0 6548.1 6565.4 6617.7 1324.5 1412.9 1502.1 1509.4 1510.5 907.7 959.5 960.8 984.8 991.5 6004.2 6024.8 6074.5 6076.5 6086.7 6566.8 6779.4 6785.2 6818.2 6824.5 5971.7 6012.8 6033.5 6047.7 6060.5 3533.7 3542.8 3564.3 3575.3 3603.4 5610.2 5836.9 6146.7 6799.5 7005.2 6376.0 6859.4 6890.8 6924.5 6966.9 6467.3 6828.4 6917.9 7046.3 7072.5 5111.5 6141.4 6506.6 6734.5 6806.5 6176.8 6328.7 6624.2 6642.2 6714.4 6444.3 6468.9 6795.7 6941.6 7084.5 3511.1 3799.9 4227.3 4338.7 4397.7 5982.7 6442.8 6828.0 6848.6 6886.8 5111.6 5619.8 5632.9 6622.4 6696.0 5522.6 6289.9 6601.5 6612.5 6688.5 5421.2 6132.2 6353.5 6478.0 6494.4 6157.3 6604.4 6617.4 6626.4 6632.9 5218.0 5682.6 6113.9 6350.8 6521.5 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17624.12 Median:30301.18 stddev:24778.14 (81.8%) stddev-sans-first:24814.96 05:02:13 INFO - PROCESS | 1664 | Values: 671.9 672.5 698.3 726.2 773.1 12362.8 14330.7 15025.3 15099.7 15139.5 15354.3 15977.0 16024.0 16037.5 16073.0 722.7 902.8 903.8 910.5 911.8 709.5 845.2 920.6 925.1 927.9 938.8 970.8 978.5 980.9 981.1 931.7 934.0 934.6 952.0 969.4 690.2 703.2 723.6 724.5 725.0 15273.2 15456.8 15545.8 15552.3 15568.3 12414.1 12442.4 12465.3 12494.2 12671.8 9963.0 9976.3 10402.8 10621.3 10762.4 3461.1 3495.8 3496.7 3576.0 3595.9 81136.0 82152.6 82343.6 82487.6 82514.6 9353.3 9364.4 9377.7 9614.6 9631.9 20888.0 20972.8 21028.0 21185.5 21327.9 7288.6 7390.5 7471.8 7512.4 7638.8 4038.4 4079.6 4089.2 4090.2 4123.2 2839.8 3539.6 3559.6 3564.0 3566.3 604.6 605.5 607.4 622.0 623.9 546.8 598.0 601.1 609.5 612.2 7139.6 7650.9 7777.1 7944.5 8055.1 506.6 520.3 534.0 558.2 595.7 3699.0 3792.9 3834.3 3867.8 3896.6 91704.5 91888.1 93762.0 94359.5 94822.0 78193.6 78325.0 78488.6 78555.8 78614.6 56800.7 61234.7 67673.0 68464.9 70342.5 7450.1 7453.7 7548.3 7593.2 7741.0 39750.9 43111.4 43854.6 44165.8 45845.5 44647.0 49134.5 55312.7 57963.5 60004.4 9137.7 10779.8 10821.9 10965.7 11241.9 11854.3 11858.3 12040.0 12303.8 12323.6 8983.6 10646.3 11030.4 11464.7 11508.8 11085.3 11228.9 11600.4 11725.1 11778.2 9266.2 11346.6 11387.4 11390.8 11581.5 9248.6 9279.2 9681.1 9706.8 9901.0 9428.9 10022.8 10038.5 10132.8 10151.3 05:02:13 INFO - PROCESS | 1664 | -------- Summary: end -------- 05:02:13 INFO - PROCESS | 1664 | 05:02:13 INFO - TEST-INFO | 1664: exit 0 05:02:14 INFO - TEST-OK | dromaeo_css | took 1112734ms 05:02:14 INFO - TEST-START | kraken 05:02:14 INFO - operating with platform_type : mac_ 05:02:14 INFO - Initialising browser for kraken test... 05:02:14 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmp_z53qs/profile http://localhost:49270/getInfo.html) 05:02:16 INFO - PROCESS | 1673 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:02:17 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 05:02:17 INFO - PROCESS | 1673 | colorDepth:24 05:02:17 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 05:02:17 INFO - PROCESS | 1673 | __metrics 05:02:17 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:02:17 INFO - TEST-INFO | 1673: exit 0 05:02:17 INFO - Browser initialized. 05:02:17 INFO - Running cycle 1/1 for kraken test... 05:02:17 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/T/tmp_z53qs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:29 INFO - PROCESS | 1676 | RSS: Main: 249327616 05:02:29 INFO - PROCESS | 1676 | 05:02:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:02:33 INFO - PROCESS | 1676 | RSS: Main: 261607424 05:02:33 INFO - PROCESS | 1676 | 05:02:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:02:36 INFO - PROCESS | 1676 | RSS: Main: 270966784 05:02:36 INFO - PROCESS | 1676 | 05:02:39 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:02:40 INFO - PROCESS | 1676 | RSS: Main: 272519168 05:02:40 INFO - PROCESS | 1676 | 05:02:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:02:43 INFO - PROCESS | 1676 | RSS: Main: 273514496 05:02:43 INFO - PROCESS | 1676 | 05:02:45 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:02:46 INFO - PROCESS | 1676 | RSS: Main: 274059264 05:02:46 INFO - PROCESS | 1676 | 05:02:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:02:49 INFO - PROCESS | 1676 | RSS: Main: 274038784 05:02:49 INFO - PROCESS | 1676 | 05:02:52 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:02:53 INFO - PROCESS | 1676 | RSS: Main: 274227200 05:02:53 INFO - PROCESS | 1676 | 05:02:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:02:56 INFO - PROCESS | 1676 | RSS: Main: 274546688 05:02:56 INFO - PROCESS | 1676 | 05:02:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:02:59 INFO - PROCESS | 1676 | RSS: Main: 274149376 05:02:59 INFO - PROCESS | 1676 | 05:03:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:01 INFO - PROCESS | 1676 | RSS: Main: 275542016 05:03:01 INFO - PROCESS | 1676 | 05:03:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:04 INFO - PROCESS | 1676 | RSS: Main: 275824640 05:03:04 INFO - PROCESS | 1676 | 05:03:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:03:08 INFO - PROCESS | 1676 | RSS: Main: 275873792 05:03:08 INFO - PROCESS | 1676 | 05:03:11 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:03:11 INFO - PROCESS | 1676 | RSS: Main: 275906560 05:03:11 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:03:13 INFO - PROCESS | 1676 | RSS: Main: 277544960 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | __start_tp_report 05:03:13 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 05:03:13 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 05:03:13 INFO - PROCESS | 1676 | |i|pagename|runs| 05:03:13 INFO - PROCESS | 1676 | |0;ai-astar;116;110;137;114;115;127;107;114;117;116 05:03:13 INFO - PROCESS | 1676 | |1;audio-beat-detection;155;132;179;171;148;170;170;124;166;121 05:03:13 INFO - PROCESS | 1676 | |2;audio-dft;194;196;202;211;211;195;197;197;200;199 05:03:13 INFO - PROCESS | 1676 | |3;audio-fft;65;123;105;180;54;77;85;90;149;49 05:03:13 INFO - PROCESS | 1676 | |4;audio-oscillator;79;88;126;93;118;129;162;80;80;116 05:03:13 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;84;88;84;88;107;138;84;137;130 05:03:13 INFO - PROCESS | 1676 | |6;imaging-darkroom;104;151;94;115;97;153;152;114;94;93 05:03:13 INFO - PROCESS | 1676 | |7;imaging-desaturate;93;92;93;90;89;89;90;94;90;91 05:03:13 INFO - PROCESS | 1676 | |8;json-parse-financial;62;62;60;60;61;61;60;79;63;61 05:03:13 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;65;68;67;75;74;72;66;67;66;69 05:03:13 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;91;109;108;109;83;82;86;82;86;85 05:03:13 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;139;138;141;170;147;161;157;162;173;161 05:03:13 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;170;172;170;152;233;166;211;227;171;171 05:03:13 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;68;71;98;95;66;62;102;98;95;85 05:03:13 INFO - PROCESS | 1676 | __end_tp_report 05:03:13 INFO - PROCESS | 1676 | __start_cc_report 05:03:13 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1028 05:03:13 INFO - PROCESS | 1676 | __end_cc_report 05:03:13 INFO - PROCESS | 1676 | __startTimestamp1460721793812__endTimestamp 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | ------- Summary: start ------- 05:03:13 INFO - PROCESS | 1676 | Number of tests: 14 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:117.30 Median:116.00 stddev:8.64 (7.4%) stddev-sans-first:9.15 05:03:13 INFO - PROCESS | 1676 | Values: 116.0 110.0 137.0 114.0 115.0 127.0 107.0 114.0 117.0 116.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:153.60 Median:168.00 stddev:21.29 (12.7%) stddev-sans-first:22.57 05:03:13 INFO - PROCESS | 1676 | Values: 155.0 132.0 179.0 171.0 148.0 170.0 170.0 124.0 166.0 121.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:200.20 Median:199.50 stddev:6.16 (3.1%) stddev-sans-first:6.11 05:03:13 INFO - PROCESS | 1676 | Values: 194.0 196.0 202.0 211.0 211.0 195.0 197.0 197.0 200.0 199.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:97.70 Median:59.50 stddev:42.29 (71.1%) stddev-sans-first:43.17 05:03:13 INFO - PROCESS | 1676 | Values: 65.0 123.0 105.0 180.0 54.0 77.0 85.0 90.0 149.0 49.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:107.10 Median:79.50 stddev:27.63 (34.8%) stddev-sans-first:27.37 05:03:13 INFO - PROCESS | 1676 | Values: 79.0 88.0 126.0 93.0 118.0 129.0 162.0 80.0 80.0 116.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:84.00 stddev:23.37 (27.8%) stddev-sans-first:24.08 05:03:13 INFO - PROCESS | 1676 | Values: 87.0 84.0 88.0 84.0 88.0 107.0 138.0 84.0 137.0 130.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:116.70 Median:123.00 stddev:25.59 (20.8%) stddev-sans-first:26.72 05:03:13 INFO - PROCESS | 1676 | Values: 104.0 151.0 94.0 115.0 97.0 153.0 152.0 114.0 94.0 93.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.50 stddev:1.79 (2.0%) stddev-sans-first:1.76 05:03:13 INFO - PROCESS | 1676 | Values: 93.0 92.0 93.0 90.0 89.0 89.0 90.0 94.0 90.0 91.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:61.50 stddev:5.74 (9.3%) stddev-sans-first:6.08 05:03:13 INFO - PROCESS | 1676 | Values: 62.0 62.0 60.0 60.0 61.0 61.0 60.0 79.0 63.0 61.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:68.50 stddev:3.54 (5.2%) stddev-sans-first:3.46 05:03:13 INFO - PROCESS | 1676 | Values: 65.0 68.0 67.0 75.0 74.0 72.0 66.0 67.0 66.0 69.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:92.10 Median:84.00 stddev:11.72 (14.0%) stddev-sans-first:12.43 05:03:13 INFO - PROCESS | 1676 | Values: 91.0 109.0 108.0 109.0 83.0 82.0 86.0 82.0 86.0 85.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:161.00 stddev:12.82 (8.0%) stddev-sans-first:12.24 05:03:13 INFO - PROCESS | 1676 | Values: 139.0 138.0 141.0 170.0 147.0 161.0 157.0 162.0 173.0 161.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.30 Median:171.50 stddev:28.28 (16.5%) stddev-sans-first:29.52 05:03:13 INFO - PROCESS | 1676 | Values: 170.0 172.0 170.0 152.0 233.0 166.0 211.0 227.0 171.0 171.0 05:03:13 INFO - PROCESS | 1676 | 05:03:13 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.00 Median:90.00 stddev:15.61 (17.3%) stddev-sans-first:15.44 05:03:13 INFO - PROCESS | 1676 | Values: 68.0 71.0 98.0 95.0 66.0 62.0 102.0 98.0 95.0 85.0 05:03:13 INFO - PROCESS | 1676 | -------- Summary: end -------- 05:03:13 INFO - PROCESS | 1676 | 05:03:14 INFO - TEST-INFO | 1676: exit 0 05:03:14 INFO - TEST-OK | kraken | took 60574ms 05:03:14 INFO - SUITE-END | took 1173s 05:03:14 INFO - Completed test suite (00:19:33) 05:03:14 INFO - javascript benchmark 05:03:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.4442067058935, 3469.219425629233, 3512.718982481402, 3533.046077559059, 3549.432090865462, 5807.47732704056, 5808.041673123935, 5826.533877289818, 5834.445727655871, 5894.970525147379, 5762.279715035621, 5875.735591898364, 5924.88150236995, 5968.164456976016, 6037.2151620289305, 5288.312519372476, 5331.466853314667, 5353.839384818461, 5378.8924221515545, 5406.567474602022, 4559.954400455986, 5386.973065134669, 5393.541548968343, 5397.0, 5428.809991650293, 5242.2136679498, 5337.279467271907, 5351.0, 5395.946040539584, 5426.782928682848, 5285.286486324336, 5285.894282114375, 5296.92054619181, 5301.628885977983, 5346.625736198468, 5263.973680131613, 5287.048331300364, 5320.042392369372, 5380.596455265871, 5390.272313237702, 5737.08206686928, 5749.936261791582, 5780.2196703444915, 5837.036888913324, 5889.381614930457, 5223.73881305937, 6127.325994140641, 6180.84547886302, 6189.6286222826775, 6207.131001659771, 5095.974520127413, 5129.358830146231, 5244.370675518924, 6282.465990390822, 6324.557280990332, 4577.107464044512, 5068.391792984866, 5660.716964151817, 5939.792107276311, 5954.583179177503, 6307.306196318401, 6376.29860715321, 6392.4886009119155, 6403.359664033559, 6408.679566021773, 6249.562530622904, 6272.749090036439, 6280.995040793451, 6288.465480434123, 6307.96846015767, 6180.351063138396, 6202.038684003986, 6254.562180647402, 6282.780102696383, 6288.371162883766, 6105.52039974972, 6203.937960620427, 6223.719932603044, 6276.152719382916, 6294.8426289342215, 6179.598326108789, 6204.069389591507, 6215.844603884939, 6245.375462453719, 6270.811875643738, 4156.625903668684, 5012.498750124958, 5013.749312534359, 5049.065922804293, 5051.040302342617, 4454.0, 4476.194285028726, 4488.169688607618, 4501.347304640874, 4522.4346956630425, 5826.184334193216, 5877.353491115974, 5877.38287479817, 5909.084091965752, 5927.525797936155, 6192.473639740582, 6211.937880621226, 6260.186175797117, 6274.529410294246, 6283.842903927347, 5847.239858818326, 5890.8821823563285, 5893.204417403681, 5897.3807750185615, 5898.174255604219, 5433.185022246695, 5449.0, 5472.452754724496, 5475.123980163155, 5491.835244942658, 5813.273340832396, 6225.657588832658, 6248.4688801452385, 6340.048992651139, 6433.871322573429, 6230.0, 6235.158253635701, 6252.374762523711, 6264.9976003839165, 6284.371562843679, 6202.5658203925295, 6456.128422663069, 6473.546851720334, 6478.384553467413, 6484.221893372825, 6228.096925945803, 6254.874902502015, 6302.590331628429, 6306.716197771203, 6318.936810632019, 5720.685362305113, 5974.671393073423, 6221.595596286227, 6276.372362763687, 6291.433770960635, 6105.0, 6147.477464415396, 6217.0, 6232.719527621359, 6280.403361680633, 6089.51283897296, 6119.837693858789, 6152.723127459186, 6221.377862213923, 6233.501319894307, 4961.627877909099, 5949.553783466171, 6105.297890742444, 6114.755409783559, 6114.816555503342, 6123.479504242189, 6178.165947597017, 6187.412195841388, 6239.78160764371, 6243.469305109116, 6099.878002440015, 6124.991279909952, 6145.0, 6152.98475751496, 6176.6911654418], "value": 5737.741730787064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.65841898299647, 508.11334221782994, 588.1031427073715, 601.9488343490804, 624.3132554190391, 27188.728112718865, 38267.234655306915, 38287.23425531488, 38335.658251961184, 38338.233235335276, 34354.14114647135, 36787.448188277194, 39536.0, 39607.405888911686, 39630.00924976877, 9386.9674335823, 9556.331056826026, 9567.952160239189, 9633.855492167622, 9661.323707340489, 9570.186534144606, 9766.023397660249, 9784.657536986208, 9788.55951482185, 9795.412275263472, 9710.417374957526, 9715.125638692516, 9777.31558790885, 9784.755381115494, 9795.755106122333, 9700.854487182698, 9737.221022318197, 9758.560864761102, 9776.755581110458, 9798.216142708638, 9464.1009104135, 9558.56986435612, 9592.376495527767, 9609.615615375376, 9610.471424071673, 37857.0, 37886.81056594727, 38340.0, 38492.0, 38511.80744096261, 3215.0997720638024, 3354.111160542434, 3448.6034106077873, 3551.449525323579, 3566.8038257895805, 28273.25610301791, 28858.855705721548, 28937.855310723524, 29705.0, 29771.404571908657, 2671.252049426164, 2707.5261829180013, 2825.0536070416433, 2829.39168078862, 2833.9716602833914, 383126.0, 444881.0, 451611.0, 451658.0, 452690.0, 61123.38876611266, 61406.0, 61636.38363616307, 61685.38314616886, 61703.84036558699, 62361.376386236465, 63531.682341588, 63661.68169159217, 63720.0, 63961.36038639554, 15871.365145394286, 16089.919550402174, 16375.263113159697, 16407.671846563, 16433.753493697604, 12297.938510307391, 12537.498500059895, 12630.747385052247, 12714.554990575285, 12835.679108022186, 15719.764203536728, 16172.595685107966, 16274.104924229045, 16320.93913895593, 16322.918385408235, 6293.748250069946, 7390.9260907390235, 7485.962570187223, 7539.283768042027, 7597.430192735567, 979.5543027922421, 1000.5497526113278, 1006.5923301062935, 1013.310948554975, 1030.4229631406436, 29594.704052959627, 29646.66590003455, 29713.85143074314, 29791.0, 29998.0, 1109.9778004440027, 1111.9888801111886, 1144.5765066925123, 1177.240679761549, 1180.3685028509706, 11775.88224117765, 14154.575362739135, 14246.002779805314, 14949.775753363709, 15075.472358467621, 460720.6963965226, 716792.0, 735470.0, 736721.0, 738212.0, 57415.71292143512, 57533.0, 58313.0, 58430.70784646049, 58503.41496585065, 1467.0, 1500.7223663622265, 1500.917449540264, 1501.2493753123438, 1509.056839475328, 25683.743162568135, 29044.854775725988, 29080.854595726887, 29896.402071958873, 29933.700662993964, 48464.7576762128, 48625.0, 48841.0, 48980.0, 49064.50935490742, 489962.0, 493597.0, 493830.0, 493831.0, 496902.0, 25092.498150037263, 25446.23661290164, 25877.09430169935, 25939.74060259373, 25957.831897564276, 44276.0, 44674.65976020724, 45103.0, 45216.54783452123, 45396.546034539235, 30035.69964300329, 31781.20546986344, 33582.0, 36881.70914017996, 37617.62382376251, 36059.0, 36164.0, 36409.45385819162, 37062.62937370592, 37247.255054899295, 35716.46425303674, 35772.64227357693, 36269.45595816012, 36616.0845978842, 37281.0, 32067.839660801546, 32248.58429504611, 32510.024699259062, 32613.34773304473, 32950.83524582458, 24274.63588046216, 24395.700116801392, 24517.387065322797, 24594.38514037092, 24606.75393246116], "value": 19403.895404437862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.5381834162323, 382.98851034468976, 391.9039835240367, 392.0786152541528, 399.53454225826914, 18585.88484690918, 19360.806391936112, 19900.0, 20176.0, 20177.0, 19399.709004364944, 20034.898080605562, 20175.899120504415, 20364.0, 20382.082806273716, 5644.491995720385, 5737.311522617281, 5935.169076329317, 5939.584229103964, 5963.194968679238, 5788.18965344852, 5805.448482394187, 5866.354700982888, 5881.117832325142, 5913.2608423947, 5605.411431799664, 5826.359100498942, 5827.300723913115, 5837.387074357195, 5855.326637436707, 5696.8860622787315, 5746.195532625436, 5800.245968024151, 5808.535317174616, 5820.883582328372, 3959.802009899522, 5066.6200034997155, 5108.846734597968, 5121.8463446096675, 5129.820456284012, 12572.559960401342, 12575.874241257561, 12830.973522118207, 12831.615051548468, 12863.099583029192, 2004.3786426207923, 2120.3744895255936, 2262.0951619352227, 2300.804431623314, 2485.8135639826905, 7596.860470929405, 9590.232781377474, 9631.614735410645, 9844.212463002943, 12840.61478155657, 1469.34614096727, 1658.78435803346, 1705.4883534939468, 1782.3405340024144, 1804.5669039430572, 82812.58593707053, 112140.0, 116473.83526164715, 117832.0, 118326.0, 8504.107068757816, 8792.824143517222, 10576.62981795649, 11236.269642473213, 11285.492152853141, 11507.827382589268, 13832.930835345758, 13989.580312590639, 14009.229492378017, 14033.1580105194, 6916.896246556305, 6933.341332573397, 8275.95862020686, 8467.618957146944, 8492.490450572985, 6448.871447496669, 8339.708110216114, 8358.331333493428, 8431.57842107892, 8503.447275927045, 4189.916201675949, 4345.717528360709, 4361.5856493633055, 4372.657504908113, 4410.20616289071, 1843.787964384086, 1873.325602782997, 1896.9945928657833, 1899.259288877339, 1926.9421917342502, 919.3932004876883, 933.2394098809449, 968.5980318168022, 979.5445118020012, 1020.1125021231428, 18526.536836579195, 19645.410637680892, 19800.306989255594, 19818.0, 19922.003899804953, 967.264878692199, 971.6842026341467, 972.1105188752213, 998.7303428074524, 1002.6340385759291, 7926.48477848938, 10190.592376304865, 10370.844437333446, 10435.00867417594, 10600.681979540626, 540948.0, 547849.0, 550887.0, 593096.0, 620375.0, 120655.39672301582, 126706.74001788707, 126967.0, 128072.0, 129786.70213297935, 991.0, 992.161623428202, 1005.5927349423495, 1009.8030883977701, 1027.1525991057408, 17502.387416440364, 17694.203760830536, 17722.91138544299, 17727.025013623857, 18042.0, 47295.52704473049, 48841.0, 49607.75196123996, 50081.747956301384, 50144.74927625339, 71626.64186679033, 72598.0, 72617.27382726104, 73207.63396183198, 75285.24714752783, 7812.257835505618, 7812.921870781447, 7912.723054693058, 7969.880451793344, 8228.382871284797, 11206.887931120584, 12415.937920310702, 12437.129400941847, 12532.561360352343, 12588.055895808035, 10769.084627806724, 10837.783244335227, 10839.0, 10947.671569852917, 10967.725806854893, 8918.597564021717, 10410.739731506774, 10676.412797295912, 10772.0, 11047.668569942914, 8982.820343593223, 10839.458027098772, 11126.388048657078, 11300.265482743594, 11354.205205635526, 7727.652255648397, 7760.728374506865, 7809.477151955314, 7967.203279671987, 8033.879491807511, 8918.821623567623, 10740.409277489813, 10843.891561084289, 10997.670069897917, 11009.889901100887], "value": 9032.090711324096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.6389584551782, 1596.3454983456782, 1821.7631707877974, 1933.69060950248, 1935.341983725533, 6960.5823650581, 7920.207979202077, 8542.316614670826, 8813.735587932373, 8874.20132188103, 8605.655773769042, 8622.741317760461, 8653.134686531344, 8718.956405217965, 8735.257503112227, 2413.770691784282, 2436.2691192642137, 2448.338948483908, 2452.3010941881566, 2460.2742191053594, 2427.332483567024, 2433.6471211674298, 2451.9754802451926, 2454.8527088374667, 2462.064415522108, 2404.8557086574774, 2413.734489206186, 2446.1438496526252, 2446.706395232575, 2455.5579995600874, 1972.6350625134253, 2375.0, 2388.916387926423, 2397.700287464067, 2418.322869596516, 2123.532822778986, 2353.670486131943, 2360.3509035015336, 2401.387646150238, 2404.903803847844, 8728.432651877607, 8766.167214114648, 8808.20726134651, 8819.735407937773, 8831.293496520328, 5094.949050509485, 5200.97399513, 5210.452902445227, 5295.417504074549, 5300.787968481256, 3249.9350012999607, 3292.2427841596327, 3339.632640409553, 3419.8461069251944, 3456.9654303457146, 969.069693094623, 995.0397866059287, 995.5271246158089, 996.6262651505685, 997.4414327976357, 11374.14693897961, 11497.367644779533, 11511.0, 11548.307101573933, 11550.884491155064, 4217.7891105444605, 4553.2469999049945, 4591.449026116888, 4729.503402142795, 4767.189577771787, 9618.086281803218, 9645.517724113906, 9821.754456138653, 9831.262655300738, 9831.852522212173, 5258.868528286747, 5273.419923808378, 5322.361316642027, 5324.680519168863, 5380.623356365095, 4376.124775045004, 4686.554777296152, 4850.75746212688, 4851.611871050308, 4857.975710121497, 3906.257811015898, 3911.413288006822, 3967.861124860616, 3995.1210733638554, 4077.062275676611, 1306.301128896036, 1312.527490103562, 1326.7412854493475, 1333.5199328241824, 1336.2383441438287, 833.3708050422014, 842.4103127810557, 894.3918135667808, 920.8204400141908, 968.6270785747538, 5653.180288858175, 5684.857878553069, 5748.45389687979, 5897.262842144732, 5955.129991639084, 388.56868875548116, 399.31716764333316, 426.99573004270184, 433.2850796186255, 455.7197323646, 3813.618638136164, 3850.133719913053, 3850.403187505941, 3893.8831835044994, 3910.7458015228917, 10077.496125193711, 10423.843642345371, 10553.0, 10558.841617375745, 10560.841587376195, 10501.9474902625, 10528.789424211473, 10540.262181647211, 10541.947290263499, 10572.788544229072, 6659.733610655519, 7963.721269755531, 8141.796455088552, 8171.67313307473, 8192.467489613156, 5613.270274864405, 5693.032184528557, 5790.334111577054, 5829.59192856496, 5937.287525496968, 9388.57751401175, 9731.805363892541, 9763.414195148314, 9787.804243915225, 9932.900670993198, 9457.054294570487, 9763.609455621696, 9905.356151850396, 9995.550200241152, 10028.348157369748, 6577.901331480128, 7132.2867713230335, 7218.566885986857, 7394.2975417335265, 7504.061992250969, 8145.837083258183, 8153.470024448392, 8157.388195885451, 8232.217939295757, 8283.58582070882, 5934.881302374015, 7039.824004399931, 7164.89252661221, 7180.964095179491, 7409.777706668809, 7112.57324560528, 7138.250483699137, 7256.637168141678, 7260.67326970277, 7329.377002954809, 6886.48351373639, 7037.577745335297, 7038.436925046087, 7045.542039767399, 7065.0815393998455, 3917.236138952877, 4082.4284600155406, 4820.566149046603, 4821.5178482151505, 4985.326980857538, 4775.2359622460235, 4913.48408417111, 4978.8257410990445, 5008.0, 5046.47012063728], "value": 4429.640917886462, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.3680394417082, 319.1908511922276, 325.9820709860957, 328.69431428771264, 330.4349562248555, 5783.739731712072, 6196.752129914804, 6306.369363063692, 6319.241690997087, 6320.431161195492, 5533.723313834303, 6013.338532761392, 6168.784092556762, 6183.598066125699, 6208.534359923013, 3949.407588861665, 3991.5808840071736, 4510.864674059783, 4519.457665080195, 4569.543045695437, 4578.908421831561, 4643.098063710181, 4663.347131401607, 4666.113438446701, 4681.555252251031, 4020.356742921133, 4565.406497155382, 4615.723056616597, 4617.215073437507, 4617.422822147231, 4395.648348132142, 4425.645948324126, 4590.632749380042, 4608.792604332813, 4619.676622636417, 3942.073612701013, 3943.0, 3976.343903255988, 3987.282289187945, 4001.239764444746, 4906.533879281463, 4931.720405930052, 5558.0, 5637.590602349413, 5994.910076348859, 4918.409790825088, 5202.531772140488, 5297.8145764898045, 5378.1663842104535, 5394.514493695587, 6504.804855854332, 6515.771947981798, 6525.510586706016, 6541.0, 6543.985682219263, 4213.47331583552, 4389.814750017509, 4393.033532622818, 4405.603495685372, 4413.867583972454, 5969.98510253258, 6665.066890635315, 6721.495887808385, 6873.312668733137, 6900.482463815184, 3555.6444355564754, 4215.515215750228, 4292.463442069741, 4421.916630425566, 4449.755263460476, 3987.3819557968563, 4095.610916962884, 4554.863354099382, 4559.156556037077, 4561.133384656904, 4606.769661516911, 5145.588352931756, 5182.507661772125, 5222.738863056832, 5243.8164664236565, 5087.491250874883, 5979.01346277868, 6548.116004339448, 6565.441937435276, 6617.735290588419, 1324.5165514587106, 1412.8728414442749, 1502.1362716437873, 1509.3509790789847, 1510.5468359492108, 907.732218995398, 959.4818797849083, 960.7646126698863, 984.7784248544164, 991.509202944547, 6004.159417681528, 6024.764923190823, 6074.4532992030045, 6076.513878889678, 6086.665233412116, 6566.835829104215, 6779.35596118368, 6785.219699734495, 6818.249992500921, 6824.469413233458, 5971.701414929236, 6012.849678758066, 6033.4871535919065, 6047.667378294148, 6060.454559089703, 3533.6643018913164, 3542.7520073595115, 3564.322778672044, 3575.2670702505925, 3603.387424137903, 5610.242617246619, 5836.941630583725, 6146.692665366803, 6799.456043516507, 7005.1944026436595, 6376.0, 6859.416949559243, 6890.758823441155, 6924.515283930177, 6966.895496567556, 6467.288598254188, 6828.398002611372, 6917.9308206918295, 7046.330598593126, 7072.469564782765, 5111.488851114858, 6141.447269745744, 6506.577072490273, 6734.528582999143, 6806.455483561403, 6176.7838125665385, 6328.6519241442165, 6624.205095388585, 6642.169728783902, 6714.3957043866285, 6444.258910225384, 6468.870622587616, 6795.728170873307, 6941.583504989717, 7084.468664850054, 3511.1046683095647, 3799.8670046548236, 4227.3447615619625, 4338.696291259708, 4397.670174736843, 5982.670953097621, 6442.8389290268115, 6828.0, 6848.623325717133, 6886.827829304308, 5111.565516930954, 5619.80330688424, 5632.9436705632415, 6622.437092846969, 6695.991202639286, 5522.5858060644805, 6289.9371006291185, 6601.53789234749, 6612.4710023198995, 6688.498362622726, 5421.213923980922, 6132.233470816148, 6353.491720662243, 6478.020110749108, 6494.415502604788, 6157.32269450369, 6604.405603495708, 6617.43751781104, 6626.403623673892, 6632.933670663231, 5217.973910130425, 5682.602217844711, 6113.877722445615, 6350.809475715736, 6521.543491955707], "value": 4687.74428185548, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.946244300456, 672.5493919074223, 698.291234397087, 726.2265687043297, 773.1495355109383, 12362.814557781641, 14330.71023607875, 15025.32386042628, 15099.698006039873, 15139.545813625595, 15354.309056092474, 15977.041377517357, 16024.0, 16037.518874433756, 16073.0, 722.6567380494249, 902.8104098139365, 903.7921278106039, 910.512875611548, 911.7902882337033, 709.5458906299972, 845.2266176448572, 920.6087412849546, 925.0841666749902, 927.9211267042309, 938.7934654376061, 970.8009857979133, 978.5107446276861, 980.9313348065639, 981.1464026297132, 931.725141083382, 934.0192797562532, 934.5887809363892, 951.9952400238024, 969.4038166527605, 690.2062627977815, 703.1772825793782, 723.5586292361655, 724.5037149552521, 724.9637518124073, 15273.236338183158, 15456.768148477782, 15545.844541554552, 15552.300146493322, 15568.299426525833, 12414.131010829245, 12442.37788110591, 12465.314407707572, 12494.187877787916, 12671.809922851164, 9963.011810438005, 9976.251781116445, 10402.791944161074, 10621.309614875008, 10762.408067556282, 3461.117415059169, 3495.7727747696317, 3496.720262379029, 3575.982120089409, 3595.928081438383, 81136.0, 82152.58923705402, 82343.5882820588, 82487.5875620624, 82514.58742706307, 9353.298502612332, 9364.391314564531, 9377.671781487616, 9614.615415383447, 9631.903680963242, 20888.0, 20972.79027209739, 21028.0, 21185.470363240733, 21327.89336053341, 7288.599127048064, 7390.5196162249285, 7471.813204669927, 7512.361449276873, 7638.815983522563, 4038.434619153321, 4079.6124368184974, 4089.182163567239, 4090.2433049885867, 4123.154753275571, 2839.801213915006, 3539.557555305587, 3559.5728512578658, 3563.982180089135, 3566.32239874423, 604.6160687963174, 605.4732382826969, 607.3501353551745, 621.9844503887439, 623.8939380305359, 546.7539607176786, 597.9595503823404, 601.0803470689851, 609.5489337890018, 612.1705089603612, 7139.607321597258, 7650.923490765133, 7777.066751989684, 7944.483608565425, 8055.113937466874, 506.6073792810601, 520.3001962360619, 534.0147427995342, 558.1794761700376, 595.7468076067654, 3698.981505092457, 3792.924141517154, 3834.2523207974177, 3867.845286188577, 3896.6103389661375, 91704.54147729352, 91888.08111918929, 93762.0, 94359.52820235855, 94822.0, 78193.60903195447, 78325.04187395361, 78488.60755696299, 78555.82166267511, 78614.606926965, 56800.71599642058, 61234.693826531475, 67673.0, 68464.89210647377, 70342.53801116937, 7450.0687414073245, 7453.701851925862, 7548.320651141204, 7593.240675932362, 7740.961295193488, 39750.91955861145, 43111.35332969945, 43854.561454385046, 44165.77917110394, 45845.54154458413, 44647.0, 49134.52596422113, 55312.72343638256, 57963.54299359087, 60004.39995600163, 9137.725868223964, 10779.838302425313, 10821.891781082088, 10965.725856853642, 11241.932016458424, 11854.348010859141, 11858.347790871585, 12039.97660198858, 12303.75392492163, 12323.630291091282, 8983.550822458721, 10646.254762166574, 11030.393328367018, 11464.713382165513, 11508.827367589325, 11085.334879907232, 11228.943855280671, 11600.361980091177, 11725.061995040547, 11778.175527712978, 9266.21237194819, 11346.602868899548, 11387.373694446886, 11390.829137563109, 11581.478833452684, 9248.583813728264, 9279.164875161268, 9681.080297371738, 9706.75733106678, 9901.0, 9428.858567121362, 10022.837723361228, 10038.53315145433, 10132.848007280045, 10151.289409741543], "value": 6764.133830878124, "unit": "score"}], "name": "dromaeo_css", "value": 7216.619369723949, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 110.0, 137.0, 114.0, 115.0, 127.0, 107.0, 114.0, 117.0, 116.0], "value": 117.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 132.0, 179.0, 171.0, 148.0, 170.0, 170.0, 124.0, 166.0, 121.0], "value": 153.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 196.0, 202.0, 211.0, 211.0, 195.0, 197.0, 197.0, 200.0, 199.0], "value": 200.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 123.0, 105.0, 180.0, 54.0, 77.0, 85.0, 90.0, 149.0, 49.0], "value": 97.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 126.0, 93.0, 118.0, 129.0, 162.0, 80.0, 80.0, 116.0], "value": 107.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 84.0, 88.0, 107.0, 138.0, 84.0, 137.0, 130.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 151.0, 94.0, 115.0, 97.0, 153.0, 152.0, 114.0, 94.0, 93.0], "value": 116.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 90.0, 89.0, 89.0, 90.0, 94.0, 90.0, 91.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 60.0, 61.0, 61.0, 60.0, 79.0, 63.0, 61.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 75.0, 74.0, 72.0, 66.0, 67.0, 66.0, 69.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 109.0, 108.0, 109.0, 83.0, 82.0, 86.0, 82.0, 86.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 141.0, 170.0, 147.0, 161.0, 157.0, 162.0, 173.0, 161.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 170.0, 152.0, 233.0, 166.0, 211.0, 227.0, 171.0, 171.0], "value": 184.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 98.0, 95.0, 66.0, 62.0, 102.0, 98.0, 95.0, 85.0], "value": 84.0, "unit": "score"}], "name": "kraken", "value": 1633.5000000000002, "alertThreshold": 2.0}]} 05:03:15 INFO - Return code: 0 05:03:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:03:15 ERROR - # TBPL SUCCESS # 05:03:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:15 INFO - 2016-04-15 05:03:15.102 screenresolution[1681:10253] starting screenresolution argv=screenresolution get 05:03:15 INFO - 2016-04-15 05:03:15.105 screenresolution[1681:10253] Display 0: 1600x1200x32@60 05:03:15 INFO - 2016-04-15 05:03:15.111 screenresolution[1682:10256] starting screenresolution argv=screenresolution list 05:03:15 INFO - Available Modes on Display 0 05:03:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:15 INFO - Intel Iris: 05:03:15 INFO - Chipset Model: Intel Iris 05:03:15 INFO - Type: GPU 05:03:15 INFO - Bus: Built-In 05:03:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:15 INFO - Vendor: Intel (0x8086) 05:03:15 INFO - Device ID: 0x0a2e 05:03:15 INFO - Revision ID: 0x0009 05:03:15 INFO - Displays: 05:03:15 INFO - Display: 05:03:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:15 INFO - Display Serial Number: 1600x1200 60 05:03:15 INFO - Main Display: Yes 05:03:15 INFO - Mirror: Off 05:03:15 INFO - Online: Yes 05:03:15 INFO - Rotation: Supported 05:03:15 INFO - Return code: 0 05:03:15 INFO - Running post-action listener: _resource_record_post_action 05:03:15 INFO - Running post-run listener: _resource_record_post_run 05:03:16 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37598720; Write bytes: 696738304; Read time: 394; Write time: 1869 05:03:16 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382861824; Write bytes: 371259392; Read time: 27997; Write time: 707 05:03:16 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31321600; Write bytes: 300378624; Read time: 308; Write time: 1112 05:03:16 INFO - Running post-run listener: _upload_blobber_files 05:03:16 INFO - Blob upload gear active. 05:03:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:03:16 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 05:03:16 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'] 05:03:16 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 05:03:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:03:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:03:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:03:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:17 INFO - (blobuploader) - INFO - Done attempting. 05:03:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:03:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:03:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:03:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:03:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:03:18 INFO - (blobuploader) - INFO - Done attempting. 05:03:18 INFO - (blobuploader) - INFO - Iteration through files over. 05:03:18 INFO - Return code: 0 05:03:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:03:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:03:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eeffe0c9ef4c898557eeb629166cb1a1fd562b117b6944170ce33566d316338c6774da40bbca22340da2b49d9a317d5c7a99418fc6891e9b9426cc7db860cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92b9500bb82edd51c3c7301e5fabf01c1ff0482fd296ab366617903affebcfa58aeb08b93327dcc1e2594834540a560f3fa0fefed852a82f567275fb8abd3c49"} 05:03:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:03:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:03:18 INFO - Contents: 05:03:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eeffe0c9ef4c898557eeb629166cb1a1fd562b117b6944170ce33566d316338c6774da40bbca22340da2b49d9a317d5c7a99418fc6891e9b9426cc7db860cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92b9500bb82edd51c3c7301e5fabf01c1ff0482fd296ab366617903affebcfa58aeb08b93327dcc1e2594834540a560f3fa0fefed852a82f567275fb8abd3c49"} 05:03:18 INFO - Running post-run listener: copy_logs_to_upload_dir 05:03:18 INFO - Copying logs to upload dir... 05:03:18 INFO - mkdir: /builds/slave/test/build/upload/logs 05:03:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.458142 ========= master_lag: 60.86 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 10 secs) (at 2016-04-15 05:04:19.601609) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-15 05:04:19.605051) ========= 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.0mikMVyVDV/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.IUAzbdGRmC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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/94eeffe0c9ef4c898557eeb629166cb1a1fd562b117b6944170ce33566d316338c6774da40bbca22340da2b49d9a317d5c7a99418fc6891e9b9426cc7db860cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92b9500bb82edd51c3c7301e5fabf01c1ff0482fd296ab366617903affebcfa58aeb08b93327dcc1e2594834540a560f3fa0fefed852a82f567275fb8abd3c49"} build_url:https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011614 build_url: 'https://queue.taskcluster.net/v1/task/HH3ZjL0NR7aWDmuiTAGnGA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eeffe0c9ef4c898557eeb629166cb1a1fd562b117b6944170ce33566d316338c6774da40bbca22340da2b49d9a317d5c7a99418fc6891e9b9426cc7db860cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92b9500bb82edd51c3c7301e5fabf01c1ff0482fd296ab366617903affebcfa58aeb08b93327dcc1e2594834540a560f3fa0fefed852a82f567275fb8abd3c49"}' ========= master_lag: 8.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-15 05:04:28.165304) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:04:28.165597) ========= 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.0mikMVyVDV/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.IUAzbdGRmC/Listeners TMPDIR=/var/folders/rh/6mxcx3y93yzc1x537jkxkp4000000w/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.005272 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:04:28.347993) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-15 05:04:28.348283) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-15 05:04:31.882985) ========= ========= Total master_lag: 71.39 =========