builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0144 starttime: 1461340851.58 results: success (0) buildid: 20160422065716 builduid: b45713089684492fa0483343b4eeab0c revision: fc15477ce628599519cb0055f52cc195d640dc94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:51.581459) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:51.582103) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-04-22 09:00:51.582576) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-04-22 09:00:55.498608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:55.499485) ========= 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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-22 09:00:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 138M=0s 2016-04-22 09:00:55 (138 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080964 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:55.786054) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:55.786365) ========= 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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.020115 ========= master_lag: 0.63 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:56.439115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 09:00:56.439439) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc15477ce628599519cb0055f52cc195d640dc94 --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 fc15477ce628599519cb0055f52cc195d640dc94 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cb8fH9gJ18/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.pTSdjfvqn8/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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-22 09:00:56,517 truncating revision to first 12 chars 2016-04-22 09:00:56,517 Setting DEBUG logging. 2016-04-22 09:00:56,517 attempt 1/10 2016-04-22 09:00:56,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc15477ce628?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 09:00:57,488 unpacking tar archive at: mozilla-central-fc15477ce628/testing/mozharness/ program finished with exit code 0 elapsedTime=1.220586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 09:00:57.679770) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:57.680068) ========= script_repo_revision: fc15477ce628599519cb0055f52cc195d640dc94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:57.680454) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:57.680731) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 09:00:57.694457) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-22 09:00:57.694884) ========= /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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:00:57 INFO - MultiFileLogger online at 20160422 09:00:57 in /builds/slave/test 09:00:57 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 09:00:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:57 INFO - {'append_to_log': False, 09:00:57 INFO - 'base_work_dir': '/builds/slave/test', 09:00:57 INFO - 'blob_upload_branch': 'Firefox', 09:00:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:57 INFO - 'branch': 'Firefox', 09:00:57 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:57 INFO - 'config_files': ('talos/mac_config.py',), 09:00:57 INFO - 'default_actions': ('clobber', 09:00:57 INFO - 'read-buildbot-config', 09:00:57 INFO - 'download-and-extract', 09:00:57 INFO - 'populate-webroot', 09:00:57 INFO - 'create-virtualenv', 09:00:57 INFO - 'install', 09:00:57 INFO - 'run-tests'), 09:00:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:57 INFO - 'download_minidump_stackwalk': True, 09:00:57 INFO - 'download_symbols': 'ondemand', 09:00:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:57 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:57 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:57 INFO - 'installer_path': 'installer.exe', 09:00:57 INFO - 'log_level': 'info', 09:00:57 INFO - 'log_name': 'talos', 09:00:57 INFO - 'log_to_console': True, 09:00:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:00:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:00:57 INFO - 'opt_config_files': (), 09:00:57 INFO - 'pip_index': False, 09:00:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:57 INFO - 'cmd': ('bash', 09:00:57 INFO - '-c', 09:00:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:57 INFO - 'enabled': True, 09:00:57 INFO - 'halt_on_failure': False, 09:00:57 INFO - 'name': 'check_screen_resolution'},), 09:00:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:57 INFO - 'cmd': ('bash', 09:00:57 INFO - '-c', 09:00:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:57 INFO - 'enabled': True, 09:00:57 INFO - 'halt_on_failure': False, 09:00:57 INFO - 'name': 'check_screen_resolution'},), 09:00:57 INFO - 'run_cmd_checks_enabled': True, 09:00:57 INFO - 'sps_profile': False, 09:00:57 INFO - 'sps_profile_interval': 0, 09:00:57 INFO - 'suite': 'dromaeojs-e10s', 09:00:57 INFO - 'system_bits': '32', 09:00:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:57 INFO - 'title': 't-yosemite-r7-0144', 09:00:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:57 INFO - 'use_talos_json': True, 09:00:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:57 INFO - 'work_dir': 'build'} 09:00:57 INFO - ##### 09:00:57 INFO - ##### Running clobber step. 09:00:57 INFO - ##### 09:00:57 INFO - Running pre-action listener: _resource_record_pre_action 09:00:57 INFO - Running main action method: clobber 09:00:57 INFO - rmtree: /builds/slave/test/build 09:00:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:58 INFO - Running post-action listener: _resource_record_post_action 09:00:58 INFO - ##### 09:00:58 INFO - ##### Running read-buildbot-config step. 09:00:58 INFO - ##### 09:00:58 INFO - Running pre-action listener: _resource_record_pre_action 09:00:58 INFO - Running main action method: read_buildbot_config 09:00:58 INFO - Using buildbot properties: 09:00:58 INFO - { 09:00:58 INFO - "project": "", 09:00:58 INFO - "product": "firefox", 09:00:58 INFO - "script_repo_revision": "production", 09:00:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:00:58 INFO - "repository": "", 09:00:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:00:58 INFO - "buildid": "20160422065716", 09:00:58 INFO - "pgo_build": "False", 09:00:58 INFO - "basedir": "/builds/slave/test", 09:00:58 INFO - "buildnumber": 201, 09:00:58 INFO - "slavename": "t-yosemite-r7-0144", 09:00:58 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:00:58 INFO - "platform": "yosemite_r7", 09:00:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:00:58 INFO - "branch": "mozilla-central", 09:00:58 INFO - "repo_path": "mozilla-central", 09:00:58 INFO - "revision": "fc15477ce628599519cb0055f52cc195d640dc94", 09:00:58 INFO - "stage_platform": "macosx64", 09:00:58 INFO - "builduid": "b45713089684492fa0483343b4eeab0c", 09:00:58 INFO - "slavebuilddir": "test" 09:00:58 INFO - } 09:00:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 09:00:58 INFO - Running post-action listener: _resource_record_post_action 09:00:58 INFO - ##### 09:00:58 INFO - ##### Running download-and-extract step. 09:00:58 INFO - ##### 09:00:58 INFO - Running pre-action listener: _resource_record_pre_action 09:00:58 INFO - Running main action method: download_and_extract 09:00:58 INFO - mkdir: /builds/slave/test/build/tests 09:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:58 INFO - https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:00:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:00:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:00:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:00:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:03 INFO - Downloaded 1199 bytes. 09:01:03 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:01:03 INFO - Using the following test package requirements: 09:01:03 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:01:03 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:03 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:01:03 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'jsshell-mac.zip'], 09:01:03 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:01:03 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:01:03 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:01:03 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:01:03 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:01:03 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:01:03 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:03 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:03 INFO - https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:01:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:01:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:04 INFO - Downloaded 18109624 bytes. 09:01:04 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'] 09:01:04 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 09:01:07 INFO - Return code: 0 09:01:07 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 09:01:07 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')]} 09:01:07 INFO - https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:01:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:01:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:15 INFO - Downloaded 18109624 bytes. 09:01:15 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'] 09:01:15 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 09:01:18 INFO - Return code: 0 09:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:18 INFO - https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:01:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:01:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:01:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:19 INFO - Downloaded 10840145 bytes. 09:01:19 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'] 09:01:19 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 09:01:20 INFO - Return code: 0 09:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:20 INFO - https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:01:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:01:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:01:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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 09:01:22 INFO - Downloaded 110069500 bytes. 09:01:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:01:22 INFO - mkdir: /builds/slave/test/properties 09:01:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:22 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:22 INFO - Contents: 09:01:22 INFO - build_url:https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:01:24 INFO - Running post-action listener: _resource_record_post_action 09:01:24 INFO - Running post-action listener: set_extra_try_arguments 09:01:24 INFO - ##### 09:01:24 INFO - ##### Running populate-webroot step. 09:01:24 INFO - ##### 09:01:24 INFO - Running pre-action listener: _resource_record_pre_action 09:01:24 INFO - Running main action method: populate_webroot 09:01:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:24 INFO - '--tppagecycles', 09:01:24 INFO - '7'], 09:01:24 INFO - 'tests': ['tsvgm']}}, 09:01:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['damp', 'tps']}, 09:01:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'tests': ['damp', 'tps']}, 09:01:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['dromaeo_dom']}, 09:01:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['a11yr', 09:01:24 INFO - 'ts_paint', 09:01:24 INFO - 'tpaint', 09:01:24 INFO - 'sessionrestore', 09:01:24 INFO - 'sessionrestore_no_auto_restore', 09:01:24 INFO - 'tabpaint']}, 09:01:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:24 INFO - 'ts_paint', 09:01:24 INFO - 'tpaint', 09:01:24 INFO - 'sessionrestore', 09:01:24 INFO - 'sessionrestore_no_auto_restore', 09:01:24 INFO - 'tabpaint']}, 09:01:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:24 INFO - 'ts_paint', 09:01:24 INFO - 'tpaint', 09:01:24 INFO - 'sessionrestore', 09:01:24 INFO - 'sessionrestore_no_auto_restore', 09:01:24 INFO - 'tabpaint']}, 09:01:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:24 INFO - 'ts_paint', 09:01:24 INFO - 'tpaint', 09:01:24 INFO - 'sessionrestore', 09:01:24 INFO - 'sessionrestore_no_auto_restore', 09:01:24 INFO - 'tabpaint']}, 09:01:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['a11yr', 09:01:24 INFO - 'ts_paint', 09:01:24 INFO - 'tpaint', 09:01:24 INFO - 'sessionrestore', 09:01:24 INFO - 'sessionrestore_no_auto_restore', 09:01:24 INFO - 'tabpaint']}, 09:01:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['a11yr', 09:01:24 INFO - 'ts_paint', 09:01:24 INFO - 'tpaint', 09:01:24 INFO - 'sessionrestore', 09:01:24 INFO - 'sessionrestore_no_auto_restore', 09:01:24 INFO - 'tabpaint']}, 09:01:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['tsvgx', 09:01:24 INFO - 'tsvgr_opacity', 09:01:24 INFO - 'tart', 09:01:24 INFO - 'tscrollx', 09:01:24 INFO - 'cart']}, 09:01:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:24 INFO - 'tsvgr_opacity', 09:01:24 INFO - 'tart', 09:01:24 INFO - 'tscrollx', 09:01:24 INFO - 'cart']}, 09:01:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'talos_options': ['--disable-e10s'], 09:01:24 INFO - 'tests': ['tp5o']}, 09:01:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'tests': ['tp5o']}, 09:01:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'talos_options': ['--disable-e10s', 09:01:24 INFO - '--xperf_path', 09:01:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:24 INFO - 'tests': ['tp5n']}, 09:01:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:24 INFO - 'talos_options': ['--xperf_path', 09:01:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:24 INFO - 'tests': ['tp5n']}}, 09:01:24 INFO - 'talos.zip': {'path': '', 09:01:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:24 INFO - Running post-action listener: _resource_record_post_action 09:01:24 INFO - ##### 09:01:24 INFO - ##### Running create-virtualenv step. 09:01:24 INFO - ##### 09:01:24 INFO - Running pre-action listener: _resource_record_pre_action 09:01:24 INFO - Running main action method: create_virtualenv 09:01:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:24 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 09:01:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:24 INFO - Using real prefix '/tools/python27' 09:01:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:28 INFO - Installing pip.................done. 09:01:28 INFO - Return code: 0 09:01:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:28 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')]} 09:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:28 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')]} 09:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:28 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:28 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 09:01:28 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 09:01:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:28 INFO - 'HOME': '/Users/cltbld', 09:01:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:28 INFO - 'LOGNAME': 'cltbld', 09:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:28 INFO - 'MOZ_NO_REMOTE': '1', 09:01:28 INFO - 'NO_EM_RESTART': '1', 09:01:28 INFO - 'PAGER': '/bin/cat', 09:01:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:28 INFO - 'PWD': '/builds/slave/test', 09:01:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:28 INFO - 'SHELL': '/bin/bash', 09:01:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:28 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:28 INFO - 'USER': 'cltbld', 09:01:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:28 INFO - 'XPC_FLAGS': '0x0', 09:01:28 INFO - 'XPC_SERVICE_NAME': '0', 09:01:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:28 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 09:01:28 INFO - Cleaning up... 09:01:28 INFO - Return code: 0 09:01:28 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:01:28 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')]} 09:01:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:28 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')]} 09:01:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:28 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:28 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 09:01:28 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 09:01:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:28 INFO - 'HOME': '/Users/cltbld', 09:01:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:28 INFO - 'LOGNAME': 'cltbld', 09:01:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:28 INFO - 'MOZ_NO_REMOTE': '1', 09:01:28 INFO - 'NO_EM_RESTART': '1', 09:01:28 INFO - 'PAGER': '/bin/cat', 09:01:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:28 INFO - 'PWD': '/builds/slave/test', 09:01:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:28 INFO - 'SHELL': '/bin/bash', 09:01:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:28 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:28 INFO - 'USER': 'cltbld', 09:01:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:28 INFO - 'XPC_FLAGS': '0x0', 09:01:28 INFO - 'XPC_SERVICE_NAME': '0', 09:01:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:28 INFO - Downloading/unpacking psutil>=0.7.1 09:01:28 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 09:01:28 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 09:01:28 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 09:01:28 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 09:01:28 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 09:01:28 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 09:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:01:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:31 INFO - Installing collected packages: psutil 09:01:31 INFO - Running setup.py install for psutil 09:01:31 INFO - building 'psutil._psutil_osx' extension 09:01:31 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 09:01:31 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 09:01:31 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 09:01:32 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 09:01:32 INFO - building 'psutil._psutil_posix' extension 09:01:32 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 09:01:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:01:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:01:32 INFO - ^ 09:01:32 INFO - 1 warning generated. 09:01:32 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 09:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:01:32 INFO - Successfully installed psutil 09:01:32 INFO - Cleaning up... 09:01:32 INFO - Return code: 0 09:01:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:01:32 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')]} 09:01:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:32 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')]} 09:01:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:32 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:32 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 09:01:32 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 09:01:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:32 INFO - 'HOME': '/Users/cltbld', 09:01:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:32 INFO - 'LOGNAME': 'cltbld', 09:01:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:32 INFO - 'MOZ_NO_REMOTE': '1', 09:01:32 INFO - 'NO_EM_RESTART': '1', 09:01:32 INFO - 'PAGER': '/bin/cat', 09:01:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:32 INFO - 'PWD': '/builds/slave/test', 09:01:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:32 INFO - 'SHELL': '/bin/bash', 09:01:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:32 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:32 INFO - 'USER': 'cltbld', 09:01:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:32 INFO - 'XPC_FLAGS': '0x0', 09:01:32 INFO - 'XPC_SERVICE_NAME': '0', 09:01:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:01:32 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 09:01:32 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 09:01:32 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 09:01:32 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 09:01:32 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 09:01:32 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 09:01:35 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:01:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:01:35 INFO - Installing collected packages: mozsystemmonitor 09:01:35 INFO - Running setup.py install for mozsystemmonitor 09:01:35 INFO - Successfully installed mozsystemmonitor 09:01:35 INFO - Cleaning up... 09:01:35 INFO - Return code: 0 09:01:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:01: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')]} 09:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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')]} 09:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01: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', '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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:01: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 blobuploader==1.2.4 09:01:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:35 INFO - 'HOME': '/Users/cltbld', 09:01:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:35 INFO - 'LOGNAME': 'cltbld', 09:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:35 INFO - 'MOZ_NO_REMOTE': '1', 09:01:35 INFO - 'NO_EM_RESTART': '1', 09:01:35 INFO - 'PAGER': '/bin/cat', 09:01:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:35 INFO - 'PWD': '/builds/slave/test', 09:01:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:35 INFO - 'SHELL': '/bin/bash', 09:01:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:35 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:35 INFO - 'USER': 'cltbld', 09:01:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:35 INFO - 'XPC_FLAGS': '0x0', 09:01:35 INFO - 'XPC_SERVICE_NAME': '0', 09:01:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:35 INFO - Downloading/unpacking blobuploader==1.2.4 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01: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 09:01:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:01:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:01:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:39 INFO - Downloading docopt-0.6.1.tar.gz 09:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:01:39 INFO - Installing collected packages: blobuploader, requests, docopt 09:01:39 INFO - Running setup.py install for blobuploader 09:01:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:01:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:01:39 INFO - Running setup.py install for requests 09:01:39 INFO - Running setup.py install for docopt 09:01:39 INFO - Successfully installed blobuploader requests docopt 09:01:39 INFO - Cleaning up... 09:01:40 INFO - Return code: 0 09:01:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:40 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:40 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 09:01:40 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 09:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:40 INFO - 'HOME': '/Users/cltbld', 09:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:40 INFO - 'LOGNAME': 'cltbld', 09:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:40 INFO - 'MOZ_NO_REMOTE': '1', 09:01:40 INFO - 'NO_EM_RESTART': '1', 09:01:40 INFO - 'PAGER': '/bin/cat', 09:01:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:40 INFO - 'PWD': '/builds/slave/test', 09:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:40 INFO - 'SHELL': '/bin/bash', 09:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:40 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:40 INFO - 'USER': 'cltbld', 09:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:40 INFO - 'XPC_FLAGS': '0x0', 09:01:40 INFO - 'XPC_SERVICE_NAME': '0', 09:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8fvw35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-8OpDju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-sOIa_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-qt6fv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-t8t0qU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-GpeE7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:40 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-CIpu0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-bHf6XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-SxSp9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-_nsSMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-VweqIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-3qWIAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-H4NeW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-vhgrGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-9RxJjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:41 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-mbafgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:42 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-PtECeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:01:42 INFO - Running setup.py install for manifestparser 09:01:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozcrash 09:01:42 INFO - Running setup.py install for mozdebug 09:01:42 INFO - Running setup.py install for mozdevice 09:01:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozfile 09:01:42 INFO - Running setup.py install for mozhttpd 09:01:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:01:42 INFO - Running setup.py install for mozinfo 09:01:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Running setup.py install for mozInstall 09:01:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Running setup.py install for mozleak 09:01:43 INFO - Running setup.py install for mozlog 09:01:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Running setup.py install for moznetwork 09:01:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:01:43 INFO - Running setup.py install for mozprocess 09:01:43 INFO - Running setup.py install for mozprofile 09:01:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Running setup.py install for mozrunner 09:01:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Running setup.py install for mozscreenshot 09:01:44 INFO - Running setup.py install for moztest 09:01:44 INFO - Running setup.py install for mozversion 09:01:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:01:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:01:44 INFO - Cleaning up... 09:01:44 INFO - Return code: 0 09:01:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:44 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')]} 09:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:44 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')]} 09:01:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:44 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:44 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 09:01:44 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 09:01:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:44 INFO - 'HOME': '/Users/cltbld', 09:01:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:44 INFO - 'LOGNAME': 'cltbld', 09:01:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:44 INFO - 'MOZ_NO_REMOTE': '1', 09:01:44 INFO - 'NO_EM_RESTART': '1', 09:01:44 INFO - 'PAGER': '/bin/cat', 09:01:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:44 INFO - 'PWD': '/builds/slave/test', 09:01:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:44 INFO - 'SHELL': '/bin/bash', 09:01:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:44 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:44 INFO - 'USER': 'cltbld', 09:01:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:44 INFO - 'XPC_FLAGS': '0x0', 09:01:44 INFO - 'XPC_SERVICE_NAME': '0', 09:01:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:01:44 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-TwTGXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-aNTFwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-jcMEvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-ejOYc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-_9Ec3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-PPecvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Okrj2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-92bdlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-aGXrvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-NhKtS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:01:45 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)) 09:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:01:45 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-FEjLtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:01:46 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)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:01:46 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Ngagva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:01:46 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)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:01:46 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-5y6SIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:01:46 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)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:01:46 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-T4ttNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:01:46 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)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:46 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-YnBynU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:01:46 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)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:01:46 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-EIYBah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:01:46 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)) 09:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:01:46 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-6H9fxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:01:46 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)) 09:01:46 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)) 09:01:46 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)) 09:01:46 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)) 09:01:46 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)) 09:01:46 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)) 09:01:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:01:46 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 09:01:46 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 09:01:46 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 09:01:46 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 09:01:46 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 09:01:46 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 09:01:49 INFO - Downloading blessings-1.6.tar.gz 09:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:49 INFO - Installing collected packages: blessings 09:01:49 INFO - Running setup.py install for blessings 09:01:49 INFO - Successfully installed blessings 09:01:49 INFO - Cleaning up... 09:01:49 INFO - Return code: 0 09:01:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:49 INFO - Reading from file tmpfile_stdout 09:01:49 INFO - Current package versions: 09:01:49 INFO - blessings == 1.6 09:01:49 INFO - blobuploader == 1.2.4 09:01:49 INFO - docopt == 0.6.1 09:01:49 INFO - manifestparser == 1.1 09:01:49 INFO - mozInstall == 1.12 09:01:49 INFO - mozcrash == 0.17 09:01:49 INFO - mozdebug == 0.1 09:01:49 INFO - mozdevice == 0.48 09:01:49 INFO - mozfile == 1.2 09:01:49 INFO - mozhttpd == 0.7 09:01:49 INFO - mozinfo == 0.9 09:01:49 INFO - mozleak == 0.1 09:01:49 INFO - mozlog == 3.1 09:01:49 INFO - moznetwork == 0.27 09:01:49 INFO - mozprocess == 0.22 09:01:49 INFO - mozprofile == 0.28 09:01:49 INFO - mozrunner == 6.11 09:01:49 INFO - mozscreenshot == 0.1 09:01:49 INFO - mozsystemmonitor == 0.0 09:01:49 INFO - moztest == 0.7 09:01:49 INFO - mozversion == 1.4 09:01:49 INFO - psutil == 3.1.1 09:01:49 INFO - requests == 1.2.3 09:01:49 INFO - wsgiref == 0.1.2 09:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:49 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')]} 09:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:49 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')]} 09:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:49 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:49 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 09:01:49 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 09:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:49 INFO - 'HOME': '/Users/cltbld', 09:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:49 INFO - 'LOGNAME': 'cltbld', 09:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:49 INFO - 'MOZ_NO_REMOTE': '1', 09:01:49 INFO - 'NO_EM_RESTART': '1', 09:01:49 INFO - 'PAGER': '/bin/cat', 09:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:49 INFO - 'PWD': '/builds/slave/test', 09:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:49 INFO - 'SHELL': '/bin/bash', 09:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:49 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:49 INFO - 'USER': 'cltbld', 09:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:49 INFO - 'XPC_FLAGS': '0x0', 09:01:49 INFO - 'XPC_SERVICE_NAME': '0', 09:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 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)) 09:01:50 INFO - Cleaning up... 09:01:50 INFO - Return code: 0 09:01:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:01:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:50 INFO - 'HOME': '/Users/cltbld', 09:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:50 INFO - 'LOGNAME': 'cltbld', 09:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:50 INFO - 'MOZ_NO_REMOTE': '1', 09:01:50 INFO - 'NO_EM_RESTART': '1', 09:01:50 INFO - 'PAGER': '/bin/cat', 09:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:50 INFO - 'PWD': '/builds/slave/test', 09:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:50 INFO - 'SHELL': '/bin/bash', 09:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:50 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:50 INFO - 'USER': 'cltbld', 09:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:50 INFO - 'XPC_FLAGS': '0x0', 09:01:50 INFO - 'XPC_SERVICE_NAME': '0', 09:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:50 INFO - Downloading/unpacking jsonschema 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:01:52 INFO - Downloading/unpacking functools32 (from jsonschema) 09:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:53 INFO - Downloading functools32-3.2.3-2.tar.gz 09:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:01:53 INFO - warning: no files found matching '*.txt' 09:01:53 INFO - no previously-included directories found matching 'build' 09:01:53 INFO - no previously-included directories found matching 'dist' 09:01:53 INFO - no previously-included directories found matching '.git*' 09:01:53 INFO - Installing collected packages: jsonschema, functools32 09:01:53 INFO - Running setup.py install for functools32 09:01:53 INFO - warning: no files found matching '*.txt' 09:01:53 INFO - no previously-included directories found matching 'build' 09:01:53 INFO - no previously-included directories found matching 'dist' 09:01:53 INFO - no previously-included directories found matching '.git*' 09:01:53 INFO - Successfully installed jsonschema functools32 09:01:53 INFO - Cleaning up... 09:01:53 INFO - Return code: 0 09:01:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:53 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 0x10d32da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d33d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb51cce970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, '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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 09:01:53 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 09:01:53 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 09:01:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:01:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:53 INFO - 'HOME': '/Users/cltbld', 09:01:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:53 INFO - 'LOGNAME': 'cltbld', 09:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:53 INFO - 'MOZ_NO_REMOTE': '1', 09:01:53 INFO - 'NO_EM_RESTART': '1', 09:01:53 INFO - 'PAGER': '/bin/cat', 09:01:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:53 INFO - 'PWD': '/builds/slave/test', 09:01:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:53 INFO - 'SHELL': '/bin/bash', 09:01:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:01:53 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:01:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:53 INFO - 'USER': 'cltbld', 09:01:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:53 INFO - 'XPC_FLAGS': '0x0', 09:01:53 INFO - 'XPC_SERVICE_NAME': '0', 09:01:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:53 INFO - Downloading/unpacking flake8 09:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:01:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:01:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:01:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:56 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 09:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:01:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:01:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:01:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:01:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:01:57 INFO - no previously-included directories found matching 'docs/_build' 09:01:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:01:57 INFO - Running setup.py install for pep8 09:01:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:01:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:01:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:01:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:01:57 INFO - no previously-included directories found matching 'docs/_build' 09:01:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:01:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:01:57 INFO - Cleaning up... 09:01:57 INFO - Return code: 0 09:01:57 INFO - Running post-action listener: _resource_record_post_action 09:01:57 INFO - Running post-action listener: _start_resource_monitoring 09:01:57 INFO - Starting resource monitoring. 09:01:57 INFO - ##### 09:01:57 INFO - ##### Running install step. 09:01:57 INFO - ##### 09:01:57 INFO - Running pre-action listener: _resource_record_pre_action 09:01:57 INFO - Running main action method: install 09:01:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:57 INFO - Reading from file tmpfile_stdout 09:01:57 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:58 INFO - Reading from file tmpfile_stdout 09:01:58 INFO - Output received: 09:01:58 INFO - Usage: mozinstall [options] installer 09:01:58 INFO - Options: 09:01:58 INFO - -h, --help show this help message and exit 09:01:58 INFO - -d DEST, --destination=DEST 09:01:58 INFO - Directory to install application into. [default: 09:01:58 INFO - "/builds/slave/test"] 09:01:58 INFO - --app=APP Application being installed. [default: firefox] 09:01:58 INFO - mkdir: /builds/slave/test/build/application 09:01:58 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'] 09:01:58 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 09:02:32 INFO - Reading from file tmpfile_stdout 09:02:32 INFO - Output received: 09:02:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:02:32 INFO - Running post-action listener: _resource_record_post_action 09:02:32 INFO - ##### 09:02:32 INFO - ##### Running run-tests step. 09:02:32 INFO - ##### 09:02:32 INFO - Running pre-action listener: _resource_record_pre_action 09:02:32 INFO - Running main action method: run_tests 09:02:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:02:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:02:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:02:32 INFO - 2016-04-22 09:02:32.553 screenresolution[1937:11249] starting screenresolution argv=screenresolution get 09:02:32 INFO - 2016-04-22 09:02:32.556 screenresolution[1937:11249] Display 0: 1600x1200x32@60 09:02:32 INFO - 2016-04-22 09:02:32.562 screenresolution[1938:11251] starting screenresolution argv=screenresolution list 09:02:32 INFO - Available Modes on Display 0 09:02:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:02:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:02:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:02:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:02:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:02:32 INFO - Intel Iris: 09:02:32 INFO - Chipset Model: Intel Iris 09:02:32 INFO - Type: GPU 09:02:32 INFO - Bus: Built-In 09:02:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:02:32 INFO - Vendor: Intel (0x8086) 09:02:32 INFO - Device ID: 0x0a2e 09:02:32 INFO - Revision ID: 0x0009 09:02:32 INFO - Displays: 09:02:32 INFO - Display: 09:02:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:02:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:02:32 INFO - Display Serial Number: 1600x1200 60 09:02:32 INFO - Main Display: Yes 09:02:32 INFO - Mirror: Off 09:02:32 INFO - Online: Yes 09:02:32 INFO - Rotation: Supported 09:02:32 INFO - Return code: 0 09:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:02:32 INFO - Python 2.7.3 09:02:32 INFO - Return code: 0 09:02:32 INFO - grabbing minidump binary from tooltool 09:02:32 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')]} 09:02:32 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 0x10d1cce00>, '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 0x10d035db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1c7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:02:32 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 09:02:32 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 09:02:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:02:32 INFO - Return code: 0 09:02:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:02:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:02:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:02:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:02:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:02:33 INFO - Return code: 0 09:02:33 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/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--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 09:02:33 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/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0144 --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 09:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cb8fH9gJ18/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:02:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pTSdjfvqn8/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:33 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/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--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 09:02:34 INFO - mozversion application_buildid: 20160422065716 09:02:34 INFO - mozversion application_changeset: fc15477ce628599519cb0055f52cc195d640dc94 09:02:34 INFO - mozversion application_display_name: Nightly 09:02:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:02:34 INFO - mozversion application_name: Firefox 09:02:34 INFO - mozversion application_remotingname: firefox 09:02:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:02:34 INFO - mozversion application_vendor: Mozilla 09:02:34 INFO - mozversion application_version: 48.0a1 09:02:34 INFO - mozversion platform_buildid: 20160422065716 09:02:34 INFO - mozversion platform_changeset: fc15477ce628599519cb0055f52cc195d640dc94 09:02:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:02:34 INFO - mozversion platform_version: 48.0a1 09:02:34 INFO - using testdate: 1461340954 09:02:34 INFO - actual date: 1461340954 09:02:34 INFO - starting webserver on 'localhost:49671' 09:02:34 INFO - SUITE-START | Running 2 tests 09:02:34 INFO - TEST-START | dromaeo_css 09:02:34 INFO - operating with platform_type : mac_ 09:02:34 INFO - Initialising browser for dromaeo_css test... 09:02:34 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpajyzFz/profile http://localhost:49671/getInfo.html) 09:02:37 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 09:02:37 INFO - PROCESS | 1956 | colorDepth:24 09:02:37 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 09:02:37 INFO - PROCESS | 1956 | __metrics 09:02:37 ERROR - PROCESS | 1956 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:02:37 INFO - TEST-INFO | 1956: exit 0 09:02:37 INFO - Browser initialized. 09:02:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:02:37 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpajyzFz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:49 INFO - PROCESS | 1959 | RSS: Main: 249671680 09:02:49 INFO - PROCESS | 1959 | 09:03:39 INFO - PROCESS | 1959 | 1461341019489 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] 09:05:51 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49671/tests/dromaeo/cssquery-ext.html) 09:05:52 INFO - PROCESS | 1959 | RSS: Main: 261685248 09:05:52 INFO - PROCESS | 1959 | 09:08:54 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-ext.html (next: http://localhost:49671/tests/dromaeo/cssquery-jquery.html) 09:08:55 INFO - PROCESS | 1959 | RSS: Main: 274010112 09:08:55 INFO - PROCESS | 1959 | 09:11:56 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49671/tests/dromaeo/cssquery-mootools.html) 09:11:57 INFO - PROCESS | 1959 | RSS: Main: 275410944 09:11:57 INFO - PROCESS | 1959 | 09:14:59 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49671/tests/dromaeo/cssquery-prototype.html) 09:14:59 INFO - PROCESS | 1959 | RSS: Main: 276971520 09:14:59 INFO - PROCESS | 1959 | 09:18:02 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49671/tests/dromaeo/cssquery-yui.html) 09:18:03 INFO - PROCESS | 1959 | RSS: Main: 279883776 09:18:03 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | Cycle 1(1): loaded http://localhost:49671/tests/dromaeo/cssquery-yui.html (next: http://localhost:49671/tests/dromaeo/cssquery-dojo.html) 09:21:05 INFO - PROCESS | 1959 | RSS: Main: 281067520 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | __start_tp_report 09:21:05 INFO - PROCESS | 1959 | _x_x_mozilla_page_load 09:21:05 INFO - PROCESS | 1959 | _x_x_mozilla_page_load_details 09:21:05 INFO - PROCESS | 1959 | |i|pagename|runs| 09:21:05 INFO - PROCESS | 1959 | |0;dojo.html;3434.2272988577565;3437.2094418283796;3456.9654303456973;3485.7734247273943;3534.5405097337343;5735.053716136843;5736.971315143423;5766.423357664232;5780.710964451781;5788.797392091277;4514.751688657123;4553.726776393419;4706.505816889228;5085.949140508594;5877.206577112089;4386.846460373888;4503.761465596955;5259.710715910624;5275.841724748245;5375.650582712111;5283.154695248761;5283.841484755463;5325.600579956499;5373.865653358658;5472.34331880175;4167.729097608661;5210.609204309654;5351.357837059539;5385.811496597639;5413.918791218115;4497.437820272466;5044.688381020925;5175.404828444739;5259.053370393328;5264.657797243205;5185.118529849897;5207.583393328525;5216.347956505437;5248.370195576517;5260.210968354739;4732.574068333832;5489.039418101816;5579.107342825169;5596.0206963781175;5664.716764161775;5074.350835978322;6161;6228.906566401508;6241.126242326078;6243.125962365272;6238.438540531329;6248.3751624837605;6249.843753906143;6261.812145635593;6263.1544741459775;5802.6808525531505;5882.735276912549;5894.675792831393;5915.3493115757665;5917.674527900921;6271.937280627227;6294.748210071546;6362.491000719931;6390.808275751735;6392.744290228432;6174.166487524127;6179.3202747697715;6183.598066125777;6196.845078873064;6222.81331560054;6134.712129681318;6135.11040899076;6286.654234017173;6292.46514046302;6304.148939893147;6099.521645064636;6133.916185115316;6140.534923917717;6195.96902015496;6252.187215662026;6235.532335074888;6256.937430625727;6260.874782504325;6264.310925798159;6299.118123462719;4302.720323178984;4880.926786098212;4883.291922671264;4926.605871530269;4937.136001199776;4365.323374876835;4402.757848318373;4425.668074894395;4433.113377324548;4483.193025255486;5779.422057794271;5783.826485205451;5838.737256823453;5848.210491583582;5859.326177489558;5266.499682530153;6142.375998161017;6315.5579109462815;6315.905261421082;6335.809925702236;5821.476067153892;5904.9704751475965;5905.114232865105;5905.970470147708;5914.9112763308585;5401.108817045222;5411.756470958816;5430.728463576806;5435.537979271727;5441.966026454961;6257.124002639725;6346.365363463617;6403.551751377452;6404.327545607644;6418.101465794793;6232.283287422005;6260.436560709558;6297.748090076346;6303.991361382157;6311.589746666452;6414.326495718069;6416.935830641633;6439.967800160969;6453.612783233021;6453.838654033687;6138.294096178996;6148.585825260126;6214.067889816563;6226.25284965807;6250.968590182569;6093.542984276286;6239.032949892774;6258.061290806416;6287.025511045796;6287.2769631492965;6107.908381374372;6196.504279657706;6198.318184999556;6218;6220;4912.263160525949;5357.089294819968;6095.238095238095;6114.6942652868065;6504.837379065561;4711.764411779329;5978.7608495661425;6021.187139736255;6098.268207814913;6122.215193971541;5408.972955135199;6062.272527296576;6147.44672979434;6151.72317245716;6166.506679465721;5869.6771677558145;5984.521238300838;6005.969970150121;6057.727402266821;6127.264728232641 09:21:05 INFO - PROCESS | 1959 | |1;ext.html;506.4707380787077;538.1685296217344;602.4969150759116;606.9681541379656;622.1600838867527;38772.80613596932;39647.40528892065;39709;39721.20557588847;39773.00567485814;41417.792911035474;41805;41823.95440113991;41878.371824422655;42046;9498.52507374632;9589.089036541518;9762.609495620201;9777.706668799947;9823.312368134233;9609.135177834029;9619.835537004188;9648.951755241214;9815.411075335467;9860.11258986691;8031.678732850708;9814.558344874462;9867;9898.50507474627;9899;9795.608175672964;9853.90146098537;9859.50702464874;9859.901400986042;9863.210943124534;9604.855927161097;9712.174465170468;9714.31999760017;9733;9746;40336;40398.99002524931;40417.59582404168;40611.593884061076;40640.593594063976;2839.304370429258;3067.6439455972572;3165.572647692555;3241.1572991022504;3397.8301084945656;26387.34031649205;26390.6041409379;26713;26803.731962680515;26880.137345224717;2584.5477041517847;2633.868306584682;2662.3077999720017;2662.986685066562;2739.260399692072;372401;372539.4119088216;373978;374876;377988;35577;35689.10777230538;35846.820765896;35882.641173588454;35931.922042338774;36757.632423675954;70831.64584177149;72465.27534724586;72918;73011.26988730044;17175.914120429567;17361.91319043422;17413.65172696539;17442.825571744375;17444.738328925076;12885.09804313689;13071.215727056408;13213.801792973112;13231.404586793617;13280;19976.200951962048;19999.600007999758;20105.597888042157;20191.798082019286;20573.07421166051;7022;7064.752733654297;8189.385796065319;8435.409521333571;8472.703455379155;962.2446379591987;995.930284880066;996.7009897030862;1004.4525733475274;1031.298716872519;30026.24934376658;30052.69947300543;30184.79260829542;30774.692253077184;30860.228494287374;954.7040417470606;971.8153550825461;975.9121679048777;1068.7915856407926;1138.65271092316;13840.031197816257;14823.629409264639;15075.849241507663;15508.844911550967;15653.60865978337;636935;642157;646324;650849.7457512682;651576;53763.92472150535;56604.73581056794;61416.771664566455;61651.38348616546;62288;1289.6131160651767;1459.839417664056;1477.94827181046;1484.1540322016347;1505.0894209003395;29323.266918327212;29390.971316003837;29443.705562944095;29807.850960745058;30199.792008319422;44244.55755442404;53582.46417535931;54391.45608544022;55042.62393440196;55169.44830551643;524949;533261;544104;545970;549397;26076.34809129787;27226.047088351814;27693.30766730848;27763.861180693966;27766.5835012479;42585.5741442594;43124.56875431331;46177.76911115423;46644.7667761659;46931;38455.23089538138;38663;38762;39259.60740392674;39729.80135099306;38132.42801358037;38218;39223;39606.40590391089;40352.3947140799;32369.676303236665;35501;36064.81967590145;36463.453048203766;36998.44502332521;29027.274318142216;33169.83415082909;33595.16012099717;33826.32347353089;33865.66134338625;24359.391015224763;24657.137000205625;24927.626085609092;24972.625410618493;25286.620700689873 09:21:05 INFO - PROCESS | 1959 | |2;jquery.html;388.90471834400574;392.44665022318526;397.18180548070956;397.4277041060872;404.8947273708837;20235.291764788217;20845.583088338226;20894.89552552237;20924;22340;20740.48148796277;21061.26285580005;21241.787582124172;21570.13719451224;21718.56562868742;5918.1418694289305;6240.5319601030105;6253.43719065284;6257.092721555372;6260.843478913041;5800.506956908668;6179.2893817210925;6188.659623720693;6245.063240513914;6251.187345645059;6099.57302988791;6159.815205543819;6177.3822617738315;6181.07283907413;6195.783147589836;5883.29400471944;6179.7837075702355;6229.501639868799;6245.156903817971;6255.624662520263;5852.444017818343;5882.911756323659;6013.458788709037;6083.239595050619;6103.908441373383;12144.878551214551;13425.462981480729;13996.440142394293;13998.72002559943;14005.929970350186;2057.2902348689827;2074.8651337663005;2166.2864861922726;2405.987970060156;2499.850008999466;9795.853062204073;10388.844167337496;10406.9479652602;10413.791724165474;10424.68725938223;1703.4293511673604;1721.0964243771994;1727.1623262717571;1754.3421217043608;1777.5111844242936;99037;110220;113799.43100284446;117276.82723172744;117844;8992;11608.941955290169;11811.409429528489;11836.349000805038;11966;14524.564263072125;14920.850791492163;14984.100953942798;15032.473863414729;15066.849331506544;7309.415246780245;7941.801454963672;8541.188587084218;8572.914270857336;8708.956455217683;7052.788416347517;8712.520811355436;8757.211850933447;8840.469571825712;8884.466931984101;4654.371659825948;4706.411698537683;4732.834350797705;4772.0694464579765;4806.423229212447;1941.9223231070882;1963.6058398537198;1986.2253721048803;1989.1645508886302;1996.1715887906646;920.7329874336501;932.9393589416666;997.4214955325893;1027.5119318323736;1062.5909025025267;15405.460808871636;20549.794502055087;20607.690884636744;20862.68792346755;21159.386999965194;855.4097672605882;933.5658918602883;933.8225737109927;941.5010044676333;972.3533849989677;9482.620695172254;10866.945665271624;10888.346699198073;10888.61889833852;10923;590908;591823;592316;611630;613664.9316753388;71967;124922;140536;145783.2710836439;147576;1042.8331466965249;1054.546544985648;1113.582406597526;1114.7547539541288;1115.2026301194685;13771.03602747798;18295;18394.632107358044;18458.077096145407;18534.88790672564;54162;55387.723061386045;56170.1574476375;56300.71849640726;56552.717236413555;79284;79941;80457;82435;82624.58687706523;7855.33229675484;7910.2089791020435;8123.309518690741;8269.95865020695;8345.66617335324;9493.76265593338;12524.937375313066;12591.93704031474;12858;12890.806637900627;10200.540975656262;10232.846507302247;11112.944435277772;11219;11447.599334023269;11095.056920161876;11198.720031999264;11214.49534770921;11458.255213411101;11542.59600913964;11498.08015358786;11619.709507262387;11644.88355116438;11711.941440292743;11765.588204413147;7786.416018798726;8196.180381961756;8284.917150828414;8516.74449766508;8575.528345940784;11095.278806877526;11215.55137794479;11441.771164576496;11662.70843228926;11823.940880295544 09:21:05 INFO - PROCESS | 1959 | |3;mootools.html;1616.943406980756;1639.1230691580004;1823.9635207295855;1848.972265416019;1966.832819210367;8005.5997200140055;8527.14728527147;8599;8907.064758200393;8916.108389161089;7298.744543940963;8564.828703425928;8752.91247087529;8754.912450875489;8783.64865405383;2446.669699590559;2497.3382053755768;2501.762332578402;2502.774750272475;2512.359348366164;2421.140495124232;2484.3416494628846;2489.8132640052067;2497.8501289922574;2499.287703004644;2451.472933319336;2454.9509009819794;2455.6562081308543;2461.495393444349;2485.763852434016;2441.353041444019;2443.389152711822;2452.534018536481;2469.160485434943;2471.283327834926;2433.415980164765;2451.6199989001734;2457.606782914743;2469.8147638927153;2471.9876400618064;8757.168069033432;8873.245774109208;8945.597448114786;8954.641814327419;8962.551872406353;5261.657992230531;5320.441353657888;5366.436524164985;5459.372172200208;5465.699386533739;3486.18074752433;3512.2273099918234;3544.149404143013;3604.4232922732235;3610.259896721192;1026.8408396698524;1027.2141811514198;1041.458441610366;1053.4469403563114;1056.6829951014665;11517.424128793611;11653.475593598308;11734.295942243407;11745.29528228309;11784.587539436165;4870.853874383774;4871.318015477836;4877.9024419512125;4979.153543897473;5041.6722913010535;8215.671373145127;10159.796804064024;10181.03280188381;10199.28604997658;10204.897951020543;5396.2984811974975;5416.674999500043;5463.48096930791;5468.589855760754;5473.31583552056;4383.013821890049;4756.881077973009;4848.466668666444;4884.902301953941;4920.335754673145;3863.555691095504;3881.204353107606;4068.06434520062;4094.0779443762194;4174.979125104355;1341.5237590655372;1381.0884816021378;1381.5095641047283;1387.5143699705022;1394.9790753138711;793.262266092528;858.3219256787193;958.6740508227097;967.2358836519071;980.3823591137538;5908.704564771744;5961.330827368344;6036.63780173191;6112.388761123941;6137.324894261627;436.58524401818397;450.3110241330769;472.3481595398328;474.8931490414629;476.53061734191795;4047.514298284225;4093.283675356801;4134.73124246929;4156.688248381383;4183.163367326547;10266.691999240034;10521.579136834442;10708.678739637824;10851.891481085087;10872.347659140476;10442.060214580571;10653.78692426147;10689.732756680989;10765.13878889687;10796.51415686296;7851.2148785121035;8127.959360203161;8227.465214761021;8235.50586964784;8253.628586713492;4708.952910470988;4831.492693267156;5977.611455255568;6000.0999850022845;6164.506839452923;9550.331476796557;9567.904320956979;9740.902590974283;9757.12185903272;9791.657291994747;9244.260459163117;9662.17871480932;9705.272104592043;10044.899551004397;10059.949700251453;6654.367835055662;7110.644467776487;7297.087864016998;7372.299631534996;7457.813554661177;8334.291585215324;8338.332933365438;8377.916220837713;8411.200935911078;8468.57657117154;7382.187959324578;7529.58587277705;7606.870076378623;7649;7662.578558179353;7403.370713489198;7486.850262994601;7531.661075251517;7617.6572054256585;7655.540667559964;7038.577685338896;7092.219855815753;7141.035960145314;7189.496735228484;7210.531315464478;4893.853184404474;5037.647364684583;5095.159298715818;5119.948800511947;5166.2508936204795;4900.681455705368;5037.672551284301;5098.388193416814;5152.742362881916;5322.361316642027 09:21:05 INFO - PROCESS | 1959 | |4;prototype.html;322.54843219492716;322.9450993331133;329.98515066822006;331.0713448776183;334.1295924117675;6125.3874612538775;6221.875562488747;6232.781852635153;6259.968700156493;6264.498840092793;6038.8490287742825;6129.203203583528;6132.540059495534;6134.877302453949;6145.784897528587;4339.804708788112;4406.625436837872;4529.184746745585;4553.157665831815;4603.171429142753;4513.232750432434;4529.21453306216;4541.046380260149;4589.967257367099;4599.862004139881;4544.909101817978;4545.068261006486;4545.454545454533;4560.794764235618;4568.862934111965;3983.570022286403;4176.728512646669;4178.937315940263;4458.955410445887;4473.888152796174;3244.7507709531765;3776.376897811858;3778.2254637799183;3818.6372294631965;3851.2105018471148;5015.39815222172;5567.969925563783;5698.373178950312;5796.768129274824;5885.911711324334;4370.38814565961;5083.466236045199;5109.41241757199;5155.7679904404395;5211.557017653504;5590.804321848756;6390.073439351315;6404.231492220917;6477.935220647827;6494.960806270974;3933.724639275252;4213.789310534492;4217.641500472463;4239.194553034913;4248.915021699549;5648.774049038034;6241.812745617639;6867.759628413031;6901.585904845725;6924.545845372478;3651.9817400912825;4133.937990930139;4275.273203555403;4287.121140166259;4310.43964284641;3855.5566109897723;4099.282625540519;4379.693421460467;4382.517923028464;4394.252976993918;4945.381827271591;5179.326687530597;5187.714675692798;5196.506331898464;5227.882707503391;6042.939570604326;6244.687765611701;6492.156019717407;6506.446952009038;6540.495685992249;1400.4888215801159;1491.033448982738;1501.9699606007819;1521.3230112600008;1523.5581681312517;903.4398672822892;953.8044700836311;964.2912459342377;980.2843923935567;987.7777500062429;6038.818835434944;6059.515598604551;6071.150038994544;6079.270487541523;6079.392060793885;6529.640869752209;6740.662966851638;6745.3591908768585;6762.492813039042;6769.526133170729;6025.8192254232445;6034.064719968412;6071.453569178795;6096.177016102858;6109.541784366191;3508.210652603195;3551.0234685461705;3552.857885684595;3559.3059353425833;3599.4960705501253;6503.642299673564;6605.504587155982;6733.057371967929;6989.0995236977105;7009.754658586925;5436.782528698887;6894.758683446155;6914.965425172842;6933.54931929433;6943.340382663639;6739.203230659319;6990.335918087773;7005.509614327152;7023.473239506955;7025.192102908129;5833.241678581927;6711.46308295345;6737.59574425536;6742.022406751092;6761.830954226184;5498.780048798163;5580.804671836466;6280;6536.346365363425;6686.06395104676;6034.163942946808;6981.755638552626;6990.134045041508;6993.307576582951;7026.437884969088;3822.2355528894886;4289.099289149313;4312.180685669713;4331.5884990925815;4350.543192964693;5136.794528218829;6633.635150066792;6656.5340426169705;6686.398224159849;6733.730650773914;6185.474234690102;6218.31598524171;6451.3548645135115;6492.967535162294;6534.803955881332;6265.718042688181;6369.20384951881;6433.839154021187;6458.837409266377;6515.73937042532;6105.023196288573;6191.628502289877;6245.96877015612;6291.307956124731;6356.11014457967;6508.60948343101;6587.868242635216;6589.505787065893;6590;6623.370779775913;5648.435156484483;6167.753289868355;6413.422791948747;6426.614403135827;6436 09:21:05 INFO - PROCESS | 1959 | |5;yui.html;651.2478087808582;664.5215444879685;738.2211766586252;762.7482930632892;774.9535027898327;11655.825162622568;14114.29428528572;14801.925990370046;15266.847331526682;15466.845331546696;15886.76169857453;15918;15929.761053584205;15964;15966;726.9491135620509;902.8284625921087;930.3673502018625;951.6383774165807;954.1079091049891;937.0676177203692;944.8441007233832;945.4185675809362;947.7346343023964;952.904709529045;964.1322809471461;987.3631507677544;993.3394292795282;994.5574219472338;996.4121168510577;938.6808485115059;946.2288235088415;950.3015283766426;982.008171746534;985.5417230987554;697.5953946710896;727.5670975769409;731.4477569435096;734.7685479074074;736.1938677148514;15604.765928511082;15633.983791053659;15652.217389130612;15686.686266274724;15691.529254122395;9694.709158725249;12542.937285313516;12559.183653062522;12809.03932205079;12842.678933026657;9211;10040;10359.326643768207;10718.517666705016;10808.51361688726;3112.501999680063;3424.5376874121926;3440.0367896988882;3493.4759786032046;3575.8748443804343;93226;94106.58840117404;94121.5293923526;95149.5242523783;95760.0423995758;9120.543972801466;9379.390339627902;9383.718488445358;9612.807743845084;9628.422294662343;21244.787552124282;21936.341909742732;22047.33857984263;22061.669074963887;22086;7338.376238019721;7340.229275926058;7384.261573842573;7533.20901305366;7613;3949.30887094763;4041.373587094005;4041.515018197835;4043.6562892153906;4050.797460126982;3576.803275819803;3607.8196090195383;3654.7989860557923;3659.1949771050326;3664.59689434162;643.1670986073046;659.3933581105395;665.8435267712084;684.9623270720158;697.1808125452573;556.4908109080227;571.074858728862;589.3546566509665;598.4524160393279;607.341034977046;6971.8605627887155;7837.3338266246865;8040.477368970998;8043.6782528698195;8154.714584989497;514.5883293365292;562.5780664501624;568.9402612725687;588.2558563417196;591.8461200088008;3216.5818443602648;3806.809659517013;3818.522684664417;3833.559140698855;3875.6511913927884;91849.62225566623;91992.54003730073;92971;93091;94463;80368.19631803608;80565.59717201376;81026.3794724114;81086.59456702796;81201.18798811936;66327.33672663308;68635.65682171658;69691;69984;70018.94971575431;7468.813279668052;7509.586972716553;7643.808904777203;7647.197044310267;7780.507372396997;42323.57676423196;43617.34573981456;43952.34071488866;44405.88985275394;44777.55222447734;63168.68415657892;63867.680661596394;64477.71044579176;64715.67642161759;64966.67516662386;9801.31390802637;9811.215102791617;9820.312578119463;9931.106200441995;10069.597216111273;10859.34843909368;10940.781184376427;10949.781004379709;11086;11298.717532061435;8161.551114688749;10312.381257124596;10334.431606261427;10363.896361036293;10495.475226238505;10203.59185632596;10284.177265818866;10322.535485903303;10407.68776936693;10611.946940265248;8290.917090829256;9971.30774690844;10241.590336386462;10243.641472548425;10407.895921040996;8653;8767.254783343487;9007.81984360296;9073.863892041492;9425.38734982224;8718.956405217934;8867.512286824287;8976.192142707188;9052.185303322733;9388.671396501362 09:21:05 INFO - PROCESS | 1959 | __end_tp_report 09:21:05 INFO - PROCESS | 1959 | __start_cc_report 09:21:05 INFO - PROCESS | 1959 | _x_x_mozilla_cycle_collect,420 09:21:05 INFO - PROCESS | 1959 | __end_cc_report 09:21:05 INFO - PROCESS | 1959 | __startTimestamp1461342065888__endTimestamp 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | ------- Summary: start ------- 09:21:05 INFO - PROCESS | 1959 | Number of tests: 6 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | [#0] dojo.html Cycles:165 Average:5705.86 Median:5978.76 stddev:704.32 (11.8%) stddev-sans-first:683.57 09:21:05 INFO - PROCESS | 1959 | Values: 3434.2 3437.2 3457.0 3485.8 3534.5 5735.1 5737.0 5766.4 5780.7 5788.8 4514.8 4553.7 4706.5 5085.9 5877.2 4386.8 4503.8 5259.7 5275.8 5375.7 5283.2 5283.8 5325.6 5373.9 5472.3 4167.7 5210.6 5351.4 5385.8 5413.9 4497.4 5044.7 5175.4 5259.1 5264.7 5185.1 5207.6 5216.3 5248.4 5260.2 4732.6 5489.0 5579.1 5596.0 5664.7 5074.4 6161.0 6228.9 6241.1 6243.1 6238.4 6248.4 6249.8 6261.8 6263.2 5802.7 5882.7 5894.7 5915.3 5917.7 6271.9 6294.7 6362.5 6390.8 6392.7 6174.2 6179.3 6183.6 6196.8 6222.8 6134.7 6135.1 6286.7 6292.5 6304.1 6099.5 6133.9 6140.5 6196.0 6252.2 6235.5 6256.9 6260.9 6264.3 6299.1 4302.7 4880.9 4883.3 4926.6 4937.1 4365.3 4402.8 4425.7 4433.1 4483.2 5779.4 5783.8 5838.7 5848.2 5859.3 5266.5 6142.4 6315.6 6315.9 6335.8 5821.5 5905.0 5905.1 5906.0 5914.9 5401.1 5411.8 5430.7 5435.5 5442.0 6257.1 6346.4 6403.6 6404.3 6418.1 6232.3 6260.4 6297.7 6304.0 6311.6 6414.3 6416.9 6440.0 6453.6 6453.8 6138.3 6148.6 6214.1 6226.3 6251.0 6093.5 6239.0 6258.1 6287.0 6287.3 6107.9 6196.5 6198.3 6218.0 6220.0 4912.3 5357.1 6095.2 6114.7 6504.8 4711.8 5978.8 6021.2 6098.3 6122.2 5409.0 6062.3 6147.4 6151.7 6166.5 5869.7 5984.5 6006.0 6057.7 6127.3 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | [#1] ext.html Cycles:180 Average:65438.81 Median:38997.90 stddev:142196.78 (364.6%) stddev-sans-first:142512.11 09:21:05 INFO - PROCESS | 1959 | Values: 506.5 538.2 602.5 607.0 622.2 38772.8 39647.4 39709.0 39721.2 39773.0 41417.8 41805.0 41824.0 41878.4 42046.0 9498.5 9589.1 9762.6 9777.7 9823.3 9609.1 9619.8 9649.0 9815.4 9860.1 8031.7 9814.6 9867.0 9898.5 9899.0 9795.6 9853.9 9859.5 9859.9 9863.2 9604.9 9712.2 9714.3 9733.0 9746.0 40336.0 40399.0 40417.6 40611.6 40640.6 2839.3 3067.6 3165.6 3241.2 3397.8 26387.3 26390.6 26713.0 26803.7 26880.1 2584.5 2633.9 2662.3 2663.0 2739.3 372401.0 372539.4 373978.0 374876.0 377988.0 35577.0 35689.1 35846.8 35882.6 35931.9 36757.6 70831.6 72465.3 72918.0 73011.3 17175.9 17361.9 17413.7 17442.8 17444.7 12885.1 13071.2 13213.8 13231.4 13280.0 19976.2 19999.6 20105.6 20191.8 20573.1 7022.0 7064.8 8189.4 8435.4 8472.7 962.2 995.9 996.7 1004.5 1031.3 30026.2 30052.7 30184.8 30774.7 30860.2 954.7 971.8 975.9 1068.8 1138.7 13840.0 14823.6 15075.8 15508.8 15653.6 636935.0 642157.0 646324.0 650849.7 651576.0 53763.9 56604.7 61416.8 61651.4 62288.0 1289.6 1459.8 1477.9 1484.2 1505.1 29323.3 29391.0 29443.7 29807.9 30199.8 44244.6 53582.5 54391.5 55042.6 55169.4 524949.0 533261.0 544104.0 545970.0 549397.0 26076.3 27226.0 27693.3 27763.9 27766.6 42585.6 43124.6 46177.8 46644.8 46931.0 38455.2 38663.0 38762.0 39259.6 39729.8 38132.4 38218.0 39223.0 39606.4 40352.4 32369.7 35501.0 36064.8 36463.5 36998.4 29027.3 33169.8 33595.2 33826.3 33865.7 24359.4 24657.1 24927.6 24972.6 25286.6 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | [#2] jquery.html Cycles:180 Average:34713.03 Median:11868.21 stddev:100167.47 (844.0%) stddev-sans-first:100415.31 09:21:05 INFO - PROCESS | 1959 | Values: 388.9 392.4 397.2 397.4 404.9 20235.3 20845.6 20894.9 20924.0 22340.0 20740.5 21061.3 21241.8 21570.1 21718.6 5918.1 6240.5 6253.4 6257.1 6260.8 5800.5 6179.3 6188.7 6245.1 6251.2 6099.6 6159.8 6177.4 6181.1 6195.8 5883.3 6179.8 6229.5 6245.2 6255.6 5852.4 5882.9 6013.5 6083.2 6103.9 12144.9 13425.5 13996.4 13998.7 14005.9 2057.3 2074.9 2166.3 2406.0 2499.9 9795.9 10388.8 10406.9 10413.8 10424.7 1703.4 1721.1 1727.2 1754.3 1777.5 99037.0 110220.0 113799.4 117276.8 117844.0 8992.0 11608.9 11811.4 11836.3 11966.0 14524.6 14920.9 14984.1 15032.5 15066.8 7309.4 7941.8 8541.2 8572.9 8709.0 7052.8 8712.5 8757.2 8840.5 8884.5 4654.4 4706.4 4732.8 4772.1 4806.4 1941.9 1963.6 1986.2 1989.2 1996.2 920.7 932.9 997.4 1027.5 1062.6 15405.5 20549.8 20607.7 20862.7 21159.4 855.4 933.6 933.8 941.5 972.4 9482.6 10866.9 10888.3 10888.6 10923.0 590908.0 591823.0 592316.0 611630.0 613664.9 71967.0 124922.0 140536.0 145783.3 147576.0 1042.8 1054.5 1113.6 1114.8 1115.2 13771.0 18295.0 18394.6 18458.1 18534.9 54162.0 55387.7 56170.2 56300.7 56552.7 79284.0 79941.0 80457.0 82435.0 82624.6 7855.3 7910.2 8123.3 8270.0 8345.7 9493.8 12524.9 12591.9 12858.0 12890.8 10200.5 10232.8 11112.9 11219.0 11447.6 11095.1 11198.7 11214.5 11458.3 11542.6 11498.1 11619.7 11644.9 11711.9 11765.6 7786.4 8196.2 8284.9 8516.7 8575.5 11095.3 11215.6 11441.8 11662.7 11823.9 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | [#3] mootools.html Cycles:180 Average:5720.19 Median:4897.27 stddev:3157.65 (64.5%) stddev-sans-first:3151.45 09:21:05 INFO - PROCESS | 1959 | Values: 1616.9 1639.1 1824.0 1849.0 1966.8 8005.6 8527.1 8599.0 8907.1 8916.1 7298.7 8564.8 8752.9 8754.9 8783.6 2446.7 2497.3 2501.8 2502.8 2512.4 2421.1 2484.3 2489.8 2497.9 2499.3 2451.5 2455.0 2455.7 2461.5 2485.8 2441.4 2443.4 2452.5 2469.2 2471.3 2433.4 2451.6 2457.6 2469.8 2472.0 8757.2 8873.2 8945.6 8954.6 8962.6 5261.7 5320.4 5366.4 5459.4 5465.7 3486.2 3512.2 3544.1 3604.4 3610.3 1026.8 1027.2 1041.5 1053.4 1056.7 11517.4 11653.5 11734.3 11745.3 11784.6 4870.9 4871.3 4877.9 4979.2 5041.7 8215.7 10159.8 10181.0 10199.3 10204.9 5396.3 5416.7 5463.5 5468.6 5473.3 4383.0 4756.9 4848.5 4884.9 4920.3 3863.6 3881.2 4068.1 4094.1 4175.0 1341.5 1381.1 1381.5 1387.5 1395.0 793.3 858.3 958.7 967.2 980.4 5908.7 5961.3 6036.6 6112.4 6137.3 436.6 450.3 472.3 474.9 476.5 4047.5 4093.3 4134.7 4156.7 4183.2 10266.7 10521.6 10708.7 10851.9 10872.3 10442.1 10653.8 10689.7 10765.1 10796.5 7851.2 8128.0 8227.5 8235.5 8253.6 4709.0 4831.5 5977.6 6000.1 6164.5 9550.3 9567.9 9740.9 9757.1 9791.7 9244.3 9662.2 9705.3 10044.9 10059.9 6654.4 7110.6 7297.1 7372.3 7457.8 8334.3 8338.3 8377.9 8411.2 8468.6 7382.2 7529.6 7606.9 7649.0 7662.6 7403.4 7486.9 7531.7 7617.7 7655.5 7038.6 7092.2 7141.0 7189.5 7210.5 4893.9 5037.6 5095.2 5119.9 5166.3 4900.7 5037.7 5098.4 5152.7 5322.4 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | [#4] prototype.html Cycles:180 Average:5241.54 Median:6071.30 stddev:1673.58 (27.6%) stddev-sans-first:1637.04 09:21:05 INFO - PROCESS | 1959 | Values: 322.5 322.9 330.0 331.1 334.1 6125.4 6221.9 6232.8 6260.0 6264.5 6038.8 6129.2 6132.5 6134.9 6145.8 4339.8 4406.6 4529.2 4553.2 4603.2 4513.2 4529.2 4541.0 4590.0 4599.9 4544.9 4545.1 4545.5 4560.8 4568.9 3983.6 4176.7 4178.9 4459.0 4473.9 3244.8 3776.4 3778.2 3818.6 3851.2 5015.4 5568.0 5698.4 5796.8 5885.9 4370.4 5083.5 5109.4 5155.8 5211.6 5590.8 6390.1 6404.2 6477.9 6495.0 3933.7 4213.8 4217.6 4239.2 4248.9 5648.8 6241.8 6867.8 6901.6 6924.5 3652.0 4133.9 4275.3 4287.1 4310.4 3855.6 4099.3 4379.7 4382.5 4394.3 4945.4 5179.3 5187.7 5196.5 5227.9 6042.9 6244.7 6492.2 6506.4 6540.5 1400.5 1491.0 1502.0 1521.3 1523.6 903.4 953.8 964.3 980.3 987.8 6038.8 6059.5 6071.2 6079.3 6079.4 6529.6 6740.7 6745.4 6762.5 6769.5 6025.8 6034.1 6071.5 6096.2 6109.5 3508.2 3551.0 3552.9 3559.3 3599.5 6503.6 6605.5 6733.1 6989.1 7009.8 5436.8 6894.8 6915.0 6933.5 6943.3 6739.2 6990.3 7005.5 7023.5 7025.2 5833.2 6711.5 6737.6 6742.0 6761.8 5498.8 5580.8 6280.0 6536.3 6686.1 6034.2 6981.8 6990.1 6993.3 7026.4 3822.2 4289.1 4312.2 4331.6 4350.5 5136.8 6633.6 6656.5 6686.4 6733.7 6185.5 6218.3 6451.4 6493.0 6534.8 6265.7 6369.2 6433.8 6458.8 6515.7 6105.0 6191.6 6246.0 6291.3 6356.1 6508.6 6587.9 6589.5 6590.0 6623.4 5648.4 6167.8 6413.4 6426.6 6436.0 09:21:05 INFO - PROCESS | 1959 | 09:21:05 INFO - PROCESS | 1959 | [#5] yui.html Cycles:180 Average:18287.96 Median:665.18 stddev:26581.12 (3996.1%) stddev-sans-first:26622.70 09:21:05 INFO - PROCESS | 1959 | Values: 651.2 664.5 738.2 762.7 775.0 11655.8 14114.3 14801.9 15266.8 15466.8 15886.8 15918.0 15929.8 15964.0 15966.0 726.9 902.8 930.4 951.6 954.1 937.1 944.8 945.4 947.7 952.9 964.1 987.4 993.3 994.6 996.4 938.7 946.2 950.3 982.0 985.5 697.6 727.6 731.4 734.8 736.2 15604.8 15634.0 15652.2 15686.7 15691.5 9694.7 12542.9 12559.2 12809.0 12842.7 9211.0 10040.0 10359.3 10718.5 10808.5 3112.5 3424.5 3440.0 3493.5 3575.9 93226.0 94106.6 94121.5 95149.5 95760.0 9120.5 9379.4 9383.7 9612.8 9628.4 21244.8 21936.3 22047.3 22061.7 22086.0 7338.4 7340.2 7384.3 7533.2 7613.0 3949.3 4041.4 4041.5 4043.7 4050.8 3576.8 3607.8 3654.8 3659.2 3664.6 643.2 659.4 665.8 685.0 697.2 556.5 571.1 589.4 598.5 607.3 6971.9 7837.3 8040.5 8043.7 8154.7 514.6 562.6 568.9 588.3 591.8 3216.6 3806.8 3818.5 3833.6 3875.7 91849.6 91992.5 92971.0 93091.0 94463.0 80368.2 80565.6 81026.4 81086.6 81201.2 66327.3 68635.7 69691.0 69984.0 70018.9 7468.8 7509.6 7643.8 7647.2 7780.5 42323.6 43617.3 43952.3 44405.9 44777.6 63168.7 63867.7 64477.7 64715.7 64966.7 9801.3 9811.2 9820.3 9931.1 10069.6 10859.3 10940.8 10949.8 11086.0 11298.7 8161.6 10312.4 10334.4 10363.9 10495.5 10203.6 10284.2 10322.5 10407.7 10611.9 8290.9 9971.3 10241.6 10243.6 10407.9 8653.0 8767.3 9007.8 9073.9 9425.4 8719.0 8867.5 8976.2 9052.2 9388.7 09:21:05 INFO - PROCESS | 1959 | -------- Summary: end -------- 09:21:05 INFO - PROCESS | 1959 | 09:21:06 INFO - TEST-INFO | 1959: exit 0 09:21:06 INFO - TEST-OK | dromaeo_css | took 1112238ms 09:21:06 INFO - TEST-START | kraken 09:21:06 INFO - operating with platform_type : mac_ 09:21:06 INFO - Initialising browser for kraken test... 09:21:06 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpe_FNdw/profile http://localhost:49671/getInfo.html) 09:21:09 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 09:21:09 INFO - PROCESS | 1964 | colorDepth:24 09:21:09 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 09:21:09 INFO - PROCESS | 1964 | __metrics 09:21:09 ERROR - PROCESS | 1964 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:21:10 INFO - TEST-INFO | 1964: exit 0 09:21:10 INFO - Browser initialized. 09:21:10 INFO - Running cycle 1/1 for kraken test... 09:21:10 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpe_FNdw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:22 INFO - PROCESS | 1967 | RSS: Main: 249454592 09:21:22 INFO - PROCESS | 1967 | 09:21:25 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:25 INFO - PROCESS | 1967 | RSS: Main: 261414912 09:21:25 INFO - PROCESS | 1967 | 09:21:28 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-dft) 09:21:29 INFO - PROCESS | 1967 | RSS: Main: 270823424 09:21:29 INFO - PROCESS | 1967 | 09:21:32 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-fft) 09:21:33 INFO - PROCESS | 1967 | RSS: Main: 273686528 09:21:33 INFO - PROCESS | 1967 | 09:21:35 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49671/tests/kraken/driver.html?testName=audio-oscillator) 09:21:35 INFO - PROCESS | 1967 | RSS: Main: 275312640 09:21:35 INFO - PROCESS | 1967 | 09:21:38 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49671/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:21:38 INFO - PROCESS | 1967 | RSS: Main: 276045824 09:21:38 INFO - PROCESS | 1967 | 09:21:41 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49671/tests/kraken/driver.html?testName=imaging-darkroom) 09:21:42 INFO - PROCESS | 1967 | RSS: Main: 275943424 09:21:42 INFO - PROCESS | 1967 | 09:21:45 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49671/tests/kraken/driver.html?testName=imaging-desaturate) 09:21:45 INFO - PROCESS | 1967 | RSS: Main: 276135936 09:21:45 INFO - PROCESS | 1967 | 09:21:48 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49671/tests/kraken/driver.html?testName=json-parse-financial) 09:21:49 INFO - PROCESS | 1967 | RSS: Main: 276598784 09:21:49 INFO - PROCESS | 1967 | 09:21:51 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49671/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:21:51 INFO - PROCESS | 1967 | RSS: Main: 276602880 09:21:51 INFO - PROCESS | 1967 | 09:21:53 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:21:54 INFO - PROCESS | 1967 | RSS: Main: 276127744 09:21:54 INFO - PROCESS | 1967 | 09:21:56 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:21:57 INFO - PROCESS | 1967 | RSS: Main: 276541440 09:21:57 INFO - PROCESS | 1967 | 09:21:59 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:00 INFO - PROCESS | 1967 | RSS: Main: 276594688 09:22:00 INFO - PROCESS | 1967 | 09:22:03 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:04 INFO - PROCESS | 1967 | RSS: Main: 276885504 09:22:04 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49671/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49671/tests/kraken/driver.html?testName=ai-astar) 09:22:06 INFO - PROCESS | 1967 | RSS: Main: 277938176 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | __start_tp_report 09:22:06 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 09:22:06 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 09:22:06 INFO - PROCESS | 1967 | |i|pagename|runs| 09:22:06 INFO - PROCESS | 1967 | |0;ai-astar;116;109;166;171;134;128;117;117;125;170 09:22:06 INFO - PROCESS | 1967 | |1;audio-beat-detection;153;123;145;167;122;141;121;161;154;120 09:22:06 INFO - PROCESS | 1967 | |2;audio-dft;200;200;205;240;258;280;205;203;198;210 09:22:06 INFO - PROCESS | 1967 | |3;audio-fft;64;73;81;138;60;57;56;66;129;72 09:22:06 INFO - PROCESS | 1967 | |4;audio-oscillator;78;88;158;115;93;79;120;79;79;120 09:22:06 INFO - PROCESS | 1967 | |5;imaging-gaussian-blur;88;86;89;86;88;102;121;87;93;120 09:22:06 INFO - PROCESS | 1967 | |6;imaging-darkroom;94;94;104;132;94;95;96;95;94;98 09:22:06 INFO - PROCESS | 1967 | |7;imaging-desaturate;90;92;89;131;94;143;76;147;146;126 09:22:06 INFO - PROCESS | 1967 | |8;json-parse-financial;62;62;61;61;60;62;64;77;64;61 09:22:06 INFO - PROCESS | 1967 | |9;json-stringify-tinderbox;62;72;68;56;100;64;67;58;65;75 09:22:06 INFO - PROCESS | 1967 | |10;stanford-crypto-aes;85;85;86;83;82;84;90;98;97;106 09:22:06 INFO - PROCESS | 1967 | |11;stanford-crypto-ccm;143;144;134;128;170;159;167;144;169;149 09:22:06 INFO - PROCESS | 1967 | |12;stanford-crypto-pbkdf2;173;176;169;181;160;225;172;175;173;204 09:22:06 INFO - PROCESS | 1967 | |13;stanford-crypto-sha256-iterative;73;89;77;74;96;97;101;86;73;71 09:22:06 INFO - PROCESS | 1967 | __end_tp_report 09:22:06 INFO - PROCESS | 1967 | __start_cc_report 09:22:06 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,1072 09:22:06 INFO - PROCESS | 1967 | __end_cc_report 09:22:06 INFO - PROCESS | 1967 | __startTimestamp1461342126361__endTimestamp 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | ------- Summary: start ------- 09:22:06 INFO - PROCESS | 1967 | Number of tests: 14 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#0] ai-astar Cycles:10 Average:135.30 Median:131.00 stddev:24.30 (18.6%) stddev-sans-first:24.75 09:22:06 INFO - PROCESS | 1967 | Values: 116.0 109.0 166.0 171.0 134.0 128.0 117.0 117.0 125.0 170.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:149.00 stddev:18.04 (12.1%) stddev-sans-first:18.58 09:22:06 INFO - PROCESS | 1967 | Values: 153.0 123.0 145.0 167.0 122.0 141.0 121.0 161.0 154.0 120.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#2] audio-dft Cycles:10 Average:219.90 Median:207.50 stddev:29.00 (14.0%) stddev-sans-first:29.85 09:22:06 INFO - PROCESS | 1967 | Values: 200.0 200.0 205.0 240.0 258.0 280.0 205.0 203.0 198.0 210.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#3] audio-fft Cycles:10 Average:79.60 Median:65.00 stddev:29.50 (45.4%) stddev-sans-first:30.75 09:22:06 INFO - PROCESS | 1967 | Values: 64.0 73.0 81.0 138.0 60.0 57.0 56.0 66.0 129.0 72.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#4] audio-oscillator Cycles:10 Average:100.90 Median:79.00 stddev:26.63 (33.7%) stddev-sans-first:26.92 09:22:06 INFO - PROCESS | 1967 | Values: 78.0 88.0 158.0 115.0 93.0 79.0 120.0 79.0 79.0 120.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:87.50 stddev:13.76 (15.7%) stddev-sans-first:14.29 09:22:06 INFO - PROCESS | 1967 | Values: 88.0 86.0 89.0 86.0 88.0 102.0 121.0 87.0 93.0 120.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:94.50 stddev:11.80 (12.5%) stddev-sans-first:12.34 09:22:06 INFO - PROCESS | 1967 | Values: 94.0 94.0 104.0 132.0 94.0 95.0 96.0 95.0 94.0 98.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#7] imaging-desaturate Cycles:10 Average:113.40 Median:82.50 stddev:27.71 (33.6%) stddev-sans-first:28.07 09:22:06 INFO - PROCESS | 1967 | Values: 90.0 92.0 89.0 131.0 94.0 143.0 76.0 147.0 146.0 126.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:62.00 stddev:4.95 (8.0%) stddev-sans-first:5.22 09:22:06 INFO - PROCESS | 1967 | Values: 62.0 62.0 61.0 61.0 60.0 62.0 64.0 77.0 64.0 61.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:66.00 stddev:12.43 (18.8%) stddev-sans-first:12.94 09:22:06 INFO - PROCESS | 1967 | Values: 62.0 72.0 68.0 56.0 100.0 64.0 67.0 58.0 65.0 75.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:85.50 stddev:8.04 (9.4%) stddev-sans-first:8.36 09:22:06 INFO - PROCESS | 1967 | Values: 85.0 85.0 86.0 83.0 82.0 84.0 90.0 98.0 97.0 106.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:154.00 stddev:14.86 (9.7%) stddev-sans-first:15.50 09:22:06 INFO - PROCESS | 1967 | Values: 143.0 144.0 134.0 128.0 170.0 159.0 167.0 144.0 169.0 149.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:175.50 stddev:19.21 (10.9%) stddev-sans-first:20.16 09:22:06 INFO - PROCESS | 1967 | Values: 173.0 176.0 169.0 181.0 160.0 225.0 172.0 175.0 173.0 204.0 09:22:06 INFO - PROCESS | 1967 | 09:22:06 INFO - PROCESS | 1967 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.70 Median:81.50 stddev:11.50 (14.1%) stddev-sans-first:11.53 09:22:06 INFO - PROCESS | 1967 | Values: 73.0 89.0 77.0 74.0 96.0 97.0 101.0 86.0 73.0 71.0 09:22:06 INFO - PROCESS | 1967 | -------- Summary: end -------- 09:22:06 INFO - PROCESS | 1967 | 09:22:07 INFO - TEST-INFO | 1967: exit 0 09:22:07 INFO - TEST-OK | kraken | took 60790ms 09:22:07 INFO - SUITE-END | took 1173s 09:22:07 INFO - Completed test suite (00:19:33) 09:22:07 INFO - javascript benchmark 09:22:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3434.2272988577565, 3437.2094418283796, 3456.9654303456973, 3485.7734247273943, 3534.5405097337343, 5735.053716136843, 5736.971315143423, 5766.423357664232, 5780.710964451781, 5788.797392091277, 4514.751688657123, 4553.726776393419, 4706.505816889228, 5085.949140508594, 5877.206577112089, 4386.846460373888, 4503.761465596955, 5259.710715910624, 5275.841724748245, 5375.650582712111, 5283.154695248761, 5283.841484755463, 5325.600579956499, 5373.865653358658, 5472.34331880175, 4167.729097608661, 5210.609204309654, 5351.357837059539, 5385.811496597639, 5413.918791218115, 4497.437820272466, 5044.688381020925, 5175.404828444739, 5259.053370393328, 5264.657797243205, 5185.118529849897, 5207.583393328525, 5216.347956505437, 5248.370195576517, 5260.210968354739, 4732.574068333832, 5489.039418101816, 5579.107342825169, 5596.0206963781175, 5664.716764161775, 5074.350835978322, 6161.0, 6228.906566401508, 6241.126242326078, 6243.125962365272, 6238.438540531329, 6248.3751624837605, 6249.843753906143, 6261.812145635593, 6263.1544741459775, 5802.6808525531505, 5882.735276912549, 5894.675792831393, 5915.3493115757665, 5917.674527900921, 6271.937280627227, 6294.748210071546, 6362.491000719931, 6390.808275751735, 6392.744290228432, 6174.166487524127, 6179.3202747697715, 6183.598066125777, 6196.845078873064, 6222.81331560054, 6134.712129681318, 6135.11040899076, 6286.654234017173, 6292.46514046302, 6304.148939893147, 6099.521645064636, 6133.916185115316, 6140.534923917717, 6195.96902015496, 6252.187215662026, 6235.532335074888, 6256.937430625727, 6260.874782504325, 6264.310925798159, 6299.118123462719, 4302.720323178984, 4880.926786098212, 4883.291922671264, 4926.605871530269, 4937.136001199776, 4365.323374876835, 4402.757848318373, 4425.668074894395, 4433.113377324548, 4483.193025255486, 5779.422057794271, 5783.826485205451, 5838.737256823453, 5848.210491583582, 5859.326177489558, 5266.499682530153, 6142.375998161017, 6315.5579109462815, 6315.905261421082, 6335.809925702236, 5821.476067153892, 5904.9704751475965, 5905.114232865105, 5905.970470147708, 5914.9112763308585, 5401.108817045222, 5411.756470958816, 5430.728463576806, 5435.537979271727, 5441.966026454961, 6257.124002639725, 6346.365363463617, 6403.551751377452, 6404.327545607644, 6418.101465794793, 6232.283287422005, 6260.436560709558, 6297.748090076346, 6303.991361382157, 6311.589746666452, 6414.326495718069, 6416.935830641633, 6439.967800160969, 6453.612783233021, 6453.838654033687, 6138.294096178996, 6148.585825260126, 6214.067889816563, 6226.25284965807, 6250.968590182569, 6093.542984276286, 6239.032949892774, 6258.061290806416, 6287.025511045796, 6287.2769631492965, 6107.908381374372, 6196.504279657706, 6198.318184999556, 6218.0, 6220.0, 4912.263160525949, 5357.089294819968, 6095.238095238095, 6114.6942652868065, 6504.837379065561, 4711.764411779329, 5978.7608495661425, 6021.187139736255, 6098.268207814913, 6122.215193971541, 5408.972955135199, 6062.272527296576, 6147.44672979434, 6151.72317245716, 6166.506679465721, 5869.6771677558145, 5984.521238300838, 6005.969970150121, 6057.727402266821, 6127.264728232641], "value": 5662.395733240626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.4707380787077, 538.1685296217344, 602.4969150759116, 606.9681541379656, 622.1600838867527, 38772.80613596932, 39647.40528892065, 39709.0, 39721.20557588847, 39773.00567485814, 41417.792911035474, 41805.0, 41823.95440113991, 41878.371824422655, 42046.0, 9498.52507374632, 9589.089036541518, 9762.609495620201, 9777.706668799947, 9823.312368134233, 9609.135177834029, 9619.835537004188, 9648.951755241214, 9815.411075335467, 9860.11258986691, 8031.678732850708, 9814.558344874462, 9867.0, 9898.50507474627, 9899.0, 9795.608175672964, 9853.90146098537, 9859.50702464874, 9859.901400986042, 9863.210943124534, 9604.855927161097, 9712.174465170468, 9714.31999760017, 9733.0, 9746.0, 40336.0, 40398.99002524931, 40417.59582404168, 40611.593884061076, 40640.593594063976, 2839.304370429258, 3067.6439455972572, 3165.572647692555, 3241.1572991022504, 3397.8301084945656, 26387.34031649205, 26390.6041409379, 26713.0, 26803.731962680515, 26880.137345224717, 2584.5477041517847, 2633.868306584682, 2662.3077999720017, 2662.986685066562, 2739.260399692072, 372401.0, 372539.4119088216, 373978.0, 374876.0, 377988.0, 35577.0, 35689.10777230538, 35846.820765896, 35882.641173588454, 35931.922042338774, 36757.632423675954, 70831.64584177149, 72465.27534724586, 72918.0, 73011.26988730044, 17175.914120429567, 17361.91319043422, 17413.65172696539, 17442.825571744375, 17444.738328925076, 12885.09804313689, 13071.215727056408, 13213.801792973112, 13231.404586793617, 13280.0, 19976.200951962048, 19999.600007999758, 20105.597888042157, 20191.798082019286, 20573.07421166051, 7022.0, 7064.752733654297, 8189.385796065319, 8435.409521333571, 8472.703455379155, 962.2446379591987, 995.930284880066, 996.7009897030862, 1004.4525733475274, 1031.298716872519, 30026.24934376658, 30052.69947300543, 30184.79260829542, 30774.692253077184, 30860.228494287374, 954.7040417470606, 971.8153550825461, 975.9121679048777, 1068.7915856407926, 1138.65271092316, 13840.031197816257, 14823.629409264639, 15075.849241507663, 15508.844911550967, 15653.60865978337, 636935.0, 642157.0, 646324.0, 650849.7457512682, 651576.0, 53763.92472150535, 56604.73581056794, 61416.771664566455, 61651.38348616546, 62288.0, 1289.6131160651767, 1459.839417664056, 1477.94827181046, 1484.1540322016347, 1505.0894209003395, 29323.266918327212, 29390.971316003837, 29443.705562944095, 29807.850960745058, 30199.792008319422, 44244.55755442404, 53582.46417535931, 54391.45608544022, 55042.62393440196, 55169.44830551643, 524949.0, 533261.0, 544104.0, 545970.0, 549397.0, 26076.34809129787, 27226.047088351814, 27693.30766730848, 27763.861180693966, 27766.5835012479, 42585.5741442594, 43124.56875431331, 46177.76911115423, 46644.7667761659, 46931.0, 38455.23089538138, 38663.0, 38762.0, 39259.60740392674, 39729.80135099306, 38132.42801358037, 38218.0, 39223.0, 39606.40590391089, 40352.3947140799, 32369.676303236665, 35501.0, 36064.81967590145, 36463.453048203766, 36998.44502332521, 29027.274318142216, 33169.83415082909, 33595.16012099717, 33826.32347353089, 33865.66134338625, 24359.391015224763, 24657.137000205625, 24927.626085609092, 24972.625410618493, 25286.620700689873], "value": 19484.441195622727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.90471834400574, 392.44665022318526, 397.18180548070956, 397.4277041060872, 404.8947273708837, 20235.291764788217, 20845.583088338226, 20894.89552552237, 20924.0, 22340.0, 20740.48148796277, 21061.26285580005, 21241.787582124172, 21570.13719451224, 21718.56562868742, 5918.1418694289305, 6240.5319601030105, 6253.43719065284, 6257.092721555372, 6260.843478913041, 5800.506956908668, 6179.2893817210925, 6188.659623720693, 6245.063240513914, 6251.187345645059, 6099.57302988791, 6159.815205543819, 6177.3822617738315, 6181.07283907413, 6195.783147589836, 5883.29400471944, 6179.7837075702355, 6229.501639868799, 6245.156903817971, 6255.624662520263, 5852.444017818343, 5882.911756323659, 6013.458788709037, 6083.239595050619, 6103.908441373383, 12144.878551214551, 13425.462981480729, 13996.440142394293, 13998.72002559943, 14005.929970350186, 2057.2902348689827, 2074.8651337663005, 2166.2864861922726, 2405.987970060156, 2499.850008999466, 9795.853062204073, 10388.844167337496, 10406.9479652602, 10413.791724165474, 10424.68725938223, 1703.4293511673604, 1721.0964243771994, 1727.1623262717571, 1754.3421217043608, 1777.5111844242936, 99037.0, 110220.0, 113799.43100284446, 117276.82723172744, 117844.0, 8992.0, 11608.941955290169, 11811.409429528489, 11836.349000805038, 11966.0, 14524.564263072125, 14920.850791492163, 14984.100953942798, 15032.473863414729, 15066.849331506544, 7309.415246780245, 7941.801454963672, 8541.188587084218, 8572.914270857336, 8708.956455217683, 7052.788416347517, 8712.520811355436, 8757.211850933447, 8840.469571825712, 8884.466931984101, 4654.371659825948, 4706.411698537683, 4732.834350797705, 4772.0694464579765, 4806.423229212447, 1941.9223231070882, 1963.6058398537198, 1986.2253721048803, 1989.1645508886302, 1996.1715887906646, 920.7329874336501, 932.9393589416666, 997.4214955325893, 1027.5119318323736, 1062.5909025025267, 15405.460808871636, 20549.794502055087, 20607.690884636744, 20862.68792346755, 21159.386999965194, 855.4097672605882, 933.5658918602883, 933.8225737109927, 941.5010044676333, 972.3533849989677, 9482.620695172254, 10866.945665271624, 10888.346699198073, 10888.61889833852, 10923.0, 590908.0, 591823.0, 592316.0, 611630.0, 613664.9316753388, 71967.0, 124922.0, 140536.0, 145783.2710836439, 147576.0, 1042.8331466965249, 1054.546544985648, 1113.582406597526, 1114.7547539541288, 1115.2026301194685, 13771.03602747798, 18295.0, 18394.632107358044, 18458.077096145407, 18534.88790672564, 54162.0, 55387.723061386045, 56170.1574476375, 56300.71849640726, 56552.717236413555, 79284.0, 79941.0, 80457.0, 82435.0, 82624.58687706523, 7855.33229675484, 7910.2089791020435, 8123.309518690741, 8269.95865020695, 8345.66617335324, 9493.76265593338, 12524.937375313066, 12591.93704031474, 12858.0, 12890.806637900627, 10200.540975656262, 10232.846507302247, 11112.944435277772, 11219.0, 11447.599334023269, 11095.056920161876, 11198.720031999264, 11214.49534770921, 11458.255213411101, 11542.59600913964, 11498.08015358786, 11619.709507262387, 11644.88355116438, 11711.941440292743, 11765.588204413147, 7786.416018798726, 8196.180381961756, 8284.917150828414, 8516.74449766508, 8575.528345940784, 11095.278806877526, 11215.55137794479, 11441.771164576496, 11662.70843228926, 11823.940880295544], "value": 9514.020731829543, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1616.943406980756, 1639.1230691580004, 1823.9635207295855, 1848.972265416019, 1966.832819210367, 8005.5997200140055, 8527.14728527147, 8599.0, 8907.064758200393, 8916.108389161089, 7298.744543940963, 8564.828703425928, 8752.91247087529, 8754.912450875489, 8783.64865405383, 2446.669699590559, 2497.3382053755768, 2501.762332578402, 2502.774750272475, 2512.359348366164, 2421.140495124232, 2484.3416494628846, 2489.8132640052067, 2497.8501289922574, 2499.287703004644, 2451.472933319336, 2454.9509009819794, 2455.6562081308543, 2461.495393444349, 2485.763852434016, 2441.353041444019, 2443.389152711822, 2452.534018536481, 2469.160485434943, 2471.283327834926, 2433.415980164765, 2451.6199989001734, 2457.606782914743, 2469.8147638927153, 2471.9876400618064, 8757.168069033432, 8873.245774109208, 8945.597448114786, 8954.641814327419, 8962.551872406353, 5261.657992230531, 5320.441353657888, 5366.436524164985, 5459.372172200208, 5465.699386533739, 3486.18074752433, 3512.2273099918234, 3544.149404143013, 3604.4232922732235, 3610.259896721192, 1026.8408396698524, 1027.2141811514198, 1041.458441610366, 1053.4469403563114, 1056.6829951014665, 11517.424128793611, 11653.475593598308, 11734.295942243407, 11745.29528228309, 11784.587539436165, 4870.853874383774, 4871.318015477836, 4877.9024419512125, 4979.153543897473, 5041.6722913010535, 8215.671373145127, 10159.796804064024, 10181.03280188381, 10199.28604997658, 10204.897951020543, 5396.2984811974975, 5416.674999500043, 5463.48096930791, 5468.589855760754, 5473.31583552056, 4383.013821890049, 4756.881077973009, 4848.466668666444, 4884.902301953941, 4920.335754673145, 3863.555691095504, 3881.204353107606, 4068.06434520062, 4094.0779443762194, 4174.979125104355, 1341.5237590655372, 1381.0884816021378, 1381.5095641047283, 1387.5143699705022, 1394.9790753138711, 793.262266092528, 858.3219256787193, 958.6740508227097, 967.2358836519071, 980.3823591137538, 5908.704564771744, 5961.330827368344, 6036.63780173191, 6112.388761123941, 6137.324894261627, 436.58524401818397, 450.3110241330769, 472.3481595398328, 474.8931490414629, 476.53061734191795, 4047.514298284225, 4093.283675356801, 4134.73124246929, 4156.688248381383, 4183.163367326547, 10266.691999240034, 10521.579136834442, 10708.678739637824, 10851.891481085087, 10872.347659140476, 10442.060214580571, 10653.78692426147, 10689.732756680989, 10765.13878889687, 10796.51415686296, 7851.2148785121035, 8127.959360203161, 8227.465214761021, 8235.50586964784, 8253.628586713492, 4708.952910470988, 4831.492693267156, 5977.611455255568, 6000.0999850022845, 6164.506839452923, 9550.331476796557, 9567.904320956979, 9740.902590974283, 9757.12185903272, 9791.657291994747, 9244.260459163117, 9662.17871480932, 9705.272104592043, 10044.899551004397, 10059.949700251453, 6654.367835055662, 7110.644467776487, 7297.087864016998, 7372.299631534996, 7457.813554661177, 8334.291585215324, 8338.332933365438, 8377.916220837713, 8411.200935911078, 8468.57657117154, 7382.187959324578, 7529.58587277705, 7606.870076378623, 7649.0, 7662.578558179353, 7403.370713489198, 7486.850262994601, 7531.661075251517, 7617.6572054256585, 7655.540667559964, 7038.577685338896, 7092.219855815753, 7141.035960145314, 7189.496735228484, 7210.531315464478, 4893.853184404474, 5037.647364684583, 5095.159298715818, 5119.948800511947, 5166.2508936204795, 4900.681455705368, 5037.672551284301, 5098.388193416814, 5152.742362881916, 5322.361316642027], "value": 4563.4584506591855, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.54843219492716, 322.9450993331133, 329.98515066822006, 331.0713448776183, 334.1295924117675, 6125.3874612538775, 6221.875562488747, 6232.781852635153, 6259.968700156493, 6264.498840092793, 6038.8490287742825, 6129.203203583528, 6132.540059495534, 6134.877302453949, 6145.784897528587, 4339.804708788112, 4406.625436837872, 4529.184746745585, 4553.157665831815, 4603.171429142753, 4513.232750432434, 4529.21453306216, 4541.046380260149, 4589.967257367099, 4599.862004139881, 4544.909101817978, 4545.068261006486, 4545.454545454533, 4560.794764235618, 4568.862934111965, 3983.570022286403, 4176.728512646669, 4178.937315940263, 4458.955410445887, 4473.888152796174, 3244.7507709531765, 3776.376897811858, 3778.2254637799183, 3818.6372294631965, 3851.2105018471148, 5015.39815222172, 5567.969925563783, 5698.373178950312, 5796.768129274824, 5885.911711324334, 4370.38814565961, 5083.466236045199, 5109.41241757199, 5155.7679904404395, 5211.557017653504, 5590.804321848756, 6390.073439351315, 6404.231492220917, 6477.935220647827, 6494.960806270974, 3933.724639275252, 4213.789310534492, 4217.641500472463, 4239.194553034913, 4248.915021699549, 5648.774049038034, 6241.812745617639, 6867.759628413031, 6901.585904845725, 6924.545845372478, 3651.9817400912825, 4133.937990930139, 4275.273203555403, 4287.121140166259, 4310.43964284641, 3855.5566109897723, 4099.282625540519, 4379.693421460467, 4382.517923028464, 4394.252976993918, 4945.381827271591, 5179.326687530597, 5187.714675692798, 5196.506331898464, 5227.882707503391, 6042.939570604326, 6244.687765611701, 6492.156019717407, 6506.446952009038, 6540.495685992249, 1400.4888215801159, 1491.033448982738, 1501.9699606007819, 1521.3230112600008, 1523.5581681312517, 903.4398672822892, 953.8044700836311, 964.2912459342377, 980.2843923935567, 987.7777500062429, 6038.818835434944, 6059.515598604551, 6071.150038994544, 6079.270487541523, 6079.392060793885, 6529.640869752209, 6740.662966851638, 6745.3591908768585, 6762.492813039042, 6769.526133170729, 6025.8192254232445, 6034.064719968412, 6071.453569178795, 6096.177016102858, 6109.541784366191, 3508.210652603195, 3551.0234685461705, 3552.857885684595, 3559.3059353425833, 3599.4960705501253, 6503.642299673564, 6605.504587155982, 6733.057371967929, 6989.0995236977105, 7009.754658586925, 5436.782528698887, 6894.758683446155, 6914.965425172842, 6933.54931929433, 6943.340382663639, 6739.203230659319, 6990.335918087773, 7005.509614327152, 7023.473239506955, 7025.192102908129, 5833.241678581927, 6711.46308295345, 6737.59574425536, 6742.022406751092, 6761.830954226184, 5498.780048798163, 5580.804671836466, 6280.0, 6536.346365363425, 6686.06395104676, 6034.163942946808, 6981.755638552626, 6990.134045041508, 6993.307576582951, 7026.437884969088, 3822.2355528894886, 4289.099289149313, 4312.180685669713, 4331.5884990925815, 4350.543192964693, 5136.794528218829, 6633.635150066792, 6656.5340426169705, 6686.398224159849, 6733.730650773914, 6185.474234690102, 6218.31598524171, 6451.3548645135115, 6492.967535162294, 6534.803955881332, 6265.718042688181, 6369.20384951881, 6433.839154021187, 6458.837409266377, 6515.73937042532, 6105.023196288573, 6191.628502289877, 6245.96877015612, 6291.307956124731, 6356.11014457967, 6508.60948343101, 6587.868242635216, 6589.505787065893, 6590.0, 6623.370779775913, 5648.435156484483, 6167.753289868355, 6413.422791948747, 6426.614403135827, 6436.0], "value": 4692.913440598494, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.2478087808582, 664.5215444879685, 738.2211766586252, 762.7482930632892, 774.9535027898327, 11655.825162622568, 14114.29428528572, 14801.925990370046, 15266.847331526682, 15466.845331546696, 15886.76169857453, 15918.0, 15929.761053584205, 15964.0, 15966.0, 726.9491135620509, 902.8284625921087, 930.3673502018625, 951.6383774165807, 954.1079091049891, 937.0676177203692, 944.8441007233832, 945.4185675809362, 947.7346343023964, 952.904709529045, 964.1322809471461, 987.3631507677544, 993.3394292795282, 994.5574219472338, 996.4121168510577, 938.6808485115059, 946.2288235088415, 950.3015283766426, 982.008171746534, 985.5417230987554, 697.5953946710896, 727.5670975769409, 731.4477569435096, 734.7685479074074, 736.1938677148514, 15604.765928511082, 15633.983791053659, 15652.217389130612, 15686.686266274724, 15691.529254122395, 9694.709158725249, 12542.937285313516, 12559.183653062522, 12809.03932205079, 12842.678933026657, 9211.0, 10040.0, 10359.326643768207, 10718.517666705016, 10808.51361688726, 3112.501999680063, 3424.5376874121926, 3440.0367896988882, 3493.4759786032046, 3575.8748443804343, 93226.0, 94106.58840117404, 94121.5293923526, 95149.5242523783, 95760.0423995758, 9120.543972801466, 9379.390339627902, 9383.718488445358, 9612.807743845084, 9628.422294662343, 21244.787552124282, 21936.341909742732, 22047.33857984263, 22061.669074963887, 22086.0, 7338.376238019721, 7340.229275926058, 7384.261573842573, 7533.20901305366, 7613.0, 3949.30887094763, 4041.373587094005, 4041.515018197835, 4043.6562892153906, 4050.797460126982, 3576.803275819803, 3607.8196090195383, 3654.7989860557923, 3659.1949771050326, 3664.59689434162, 643.1670986073046, 659.3933581105395, 665.8435267712084, 684.9623270720158, 697.1808125452573, 556.4908109080227, 571.074858728862, 589.3546566509665, 598.4524160393279, 607.341034977046, 6971.8605627887155, 7837.3338266246865, 8040.477368970998, 8043.6782528698195, 8154.714584989497, 514.5883293365292, 562.5780664501624, 568.9402612725687, 588.2558563417196, 591.8461200088008, 3216.5818443602648, 3806.809659517013, 3818.522684664417, 3833.559140698855, 3875.6511913927884, 91849.62225566623, 91992.54003730073, 92971.0, 93091.0, 94463.0, 80368.19631803608, 80565.59717201376, 81026.3794724114, 81086.59456702796, 81201.18798811936, 66327.33672663308, 68635.65682171658, 69691.0, 69984.0, 70018.94971575431, 7468.813279668052, 7509.586972716553, 7643.808904777203, 7647.197044310267, 7780.507372396997, 42323.57676423196, 43617.34573981456, 43952.34071488866, 44405.88985275394, 44777.55222447734, 63168.68415657892, 63867.680661596394, 64477.71044579176, 64715.67642161759, 64966.67516662386, 9801.31390802637, 9811.215102791617, 9820.312578119463, 9931.106200441995, 10069.597216111273, 10859.34843909368, 10940.781184376427, 10949.781004379709, 11086.0, 11298.717532061435, 8161.551114688749, 10312.381257124596, 10334.431606261427, 10363.896361036293, 10495.475226238505, 10203.59185632596, 10284.177265818866, 10322.535485903303, 10407.68776936693, 10611.946940265248, 8290.917090829256, 9971.30774690844, 10241.590336386462, 10243.641472548425, 10407.895921040996, 8653.0, 8767.254783343487, 9007.81984360296, 9073.863892041492, 9425.38734982224, 8718.956405217934, 8867.512286824287, 8976.192142707188, 9052.185303322733, 9388.671396501362], "value": 6782.251707726806, "unit": "score"}], "name": "dromaeo_css", "value": 7309.154006955549, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 166.0, 171.0, 134.0, 128.0, 117.0, 117.0, 125.0, 170.0], "value": 135.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 145.0, 167.0, 122.0, 141.0, 121.0, 161.0, 154.0, 120.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 205.0, 240.0, 258.0, 280.0, 205.0, 203.0, 198.0, 210.0], "value": 219.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 81.0, 138.0, 60.0, 57.0, 56.0, 66.0, 129.0, 72.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 88.0, 158.0, 115.0, 93.0, 79.0, 120.0, 79.0, 79.0, 120.0], "value": 100.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 102.0, 121.0, 87.0, 93.0, 120.0], "value": 96.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 104.0, 132.0, 94.0, 95.0, 96.0, 95.0, 94.0, 98.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 89.0, 131.0, 94.0, 143.0, 76.0, 147.0, 146.0, 126.0], "value": 113.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 60.0, 62.0, 64.0, 77.0, 64.0, 61.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 72.0, 68.0, 56.0, 100.0, 64.0, 67.0, 58.0, 65.0, 75.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 83.0, 82.0, 84.0, 90.0, 98.0, 97.0, 106.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 134.0, 128.0, 170.0, 159.0, 167.0, 144.0, 169.0, 149.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 176.0, 169.0, 181.0, 160.0, 225.0, 172.0, 175.0, 173.0, 204.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 89.0, 77.0, 74.0, 96.0, 97.0, 101.0, 86.0, 73.0, 71.0], "value": 83.7, "unit": "score"}], "name": "kraken", "value": 1622.3, "alertThreshold": 2.0}]} 09:22:07 INFO - Return code: 0 09:22:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:07 ERROR - # TBPL SUCCESS # 09:22:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:07 INFO - 2016-04-22 09:22:07.686 screenresolution[1972:13949] starting screenresolution argv=screenresolution get 09:22:07 INFO - 2016-04-22 09:22:07.689 screenresolution[1972:13949] Display 0: 1600x1200x32@60 09:22:07 INFO - 2016-04-22 09:22:07.695 screenresolution[1973:13952] starting screenresolution argv=screenresolution list 09:22:07 INFO - Available Modes on Display 0 09:22:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:07 INFO - Intel Iris: 09:22:07 INFO - Chipset Model: Intel Iris 09:22:07 INFO - Type: GPU 09:22:07 INFO - Bus: Built-In 09:22:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:07 INFO - Vendor: Intel (0x8086) 09:22:07 INFO - Device ID: 0x0a2e 09:22:07 INFO - Revision ID: 0x0009 09:22:07 INFO - Displays: 09:22:07 INFO - Display: 09:22:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:07 INFO - Display Serial Number: 1600x1200 60 09:22:07 INFO - Main Display: Yes 09:22:07 INFO - Mirror: Off 09:22:07 INFO - Online: Yes 09:22:07 INFO - Rotation: Supported 09:22:07 INFO - Return code: 0 09:22:07 INFO - Running post-action listener: _resource_record_post_action 09:22:07 INFO - Running post-run listener: _resource_record_post_run 09:22:09 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 94208; Write bytes: 695729664; Read time: 4; Write time: 1842 09:22:09 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 379943424; Write bytes: 373491200; Read time: 29435; Write time: 641 09:22:09 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 94208; Write bytes: 299087872; Read time: 4; Write time: 1153 09:22:09 INFO - Running post-run listener: _upload_blobber_files 09:22:09 INFO - Blob upload gear active. 09:22:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:22:09 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'] 09:22:09 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 09:22:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:22:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:10 INFO - (blobuploader) - INFO - Done attempting. 09:22:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:22:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:22:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:11 INFO - (blobuploader) - INFO - Done attempting. 09:22:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:11 INFO - Return code: 0 09:22:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e513a78ab818a23f7817831ac573d9667186e5f46754f51de91775edea1e721638320bc38c0b6e30f572acf15ec84d156bd82c9f98dbc8b1197f46dcc92e286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e219b37a8bdd40cbb7080548149463023692f7c200af64c33c1beed7a80836eb80a9e4c176595ee50fdd2aae3a94fa544c38e80667dfa56ac19a7cb99e5acf"} 09:22:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:11 INFO - Contents: 09:22:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e513a78ab818a23f7817831ac573d9667186e5f46754f51de91775edea1e721638320bc38c0b6e30f572acf15ec84d156bd82c9f98dbc8b1197f46dcc92e286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e219b37a8bdd40cbb7080548149463023692f7c200af64c33c1beed7a80836eb80a9e4c176595ee50fdd2aae3a94fa544c38e80667dfa56ac19a7cb99e5acf"} 09:22:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:11 INFO - Copying logs to upload dir... 09:22:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.534092 ========= master_lag: 1.81 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-22 09:22:13.042598) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 09:22:13.046087) ========= 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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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/e513a78ab818a23f7817831ac573d9667186e5f46754f51de91775edea1e721638320bc38c0b6e30f572acf15ec84d156bd82c9f98dbc8b1197f46dcc92e286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e219b37a8bdd40cbb7080548149463023692f7c200af64c33c1beed7a80836eb80a9e4c176595ee50fdd2aae3a94fa544c38e80667dfa56ac19a7cb99e5acf"} build_url:https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/DQcaCAoGTri3OYCoZmQ4MA/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/e513a78ab818a23f7817831ac573d9667186e5f46754f51de91775edea1e721638320bc38c0b6e30f572acf15ec84d156bd82c9f98dbc8b1197f46dcc92e286d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33e219b37a8bdd40cbb7080548149463023692f7c200af64c33c1beed7a80836eb80a9e4c176595ee50fdd2aae3a94fa544c38e80667dfa56ac19a7cb99e5acf"}' ========= master_lag: 0.90 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 09:22:13.960677) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 09:22:13.960991) ========= 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.cb8fH9gJ18/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.pTSdjfvqn8/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005185 ========= master_lag: 1.57 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 09:22:15.540213) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 09:22:15.540494) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 09:22:16.007207) ========= ========= Total master_lag: 5.15 =========