builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0165 starttime: 1494070902.7 results: success (0) buildid: 20170506030204 builduid: 905116b528b2426f8a0f55bff1cfb8de revision: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:42.701236) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:42.701634) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:42.701915) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:42.727714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:42.728073) ========= 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.6UwdKrlCHf/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.DqVKeennOS/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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 --2017-05-06 04:41:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 134M=0s 2017-05-06 04:41:43 (134 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.539693 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:43.292235) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:43.292574) ========= 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.6UwdKrlCHf/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.DqVKeennOS/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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.020868 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:43.340684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-06 04:41:43.341023) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 --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 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6UwdKrlCHf/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.DqVKeennOS/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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 2017-05-06 04:41:43,389 truncating revision to first 12 chars 2017-05-06 04:41:43,389 Setting DEBUG logging. 2017-05-06 04:41:43,389 attempt 1/10 2017-05-06 04:41:43,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37a5b7f6f101?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-06 04:41:44,357 unpacking tar archive at: mozilla-central-37a5b7f6f101/testing/mozharness/ program finished with exit code 0 elapsedTime=1.246553 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-06 04:41:44.611960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:44.612298) ========= script_repo_revision: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:44.612700) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:44.612963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-06 04:41:44.633570) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-06 04:41:44.633925) ========= /tools/buildbot/bin/python -u 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', '-u', '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.6UwdKrlCHf/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.DqVKeennOS/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:41:44 INFO - MultiFileLogger online at 20170506 04:41:44 in /builds/slave/test 04:41:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:41:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:41:44 INFO - {'append_to_log': False, 04:41:44 INFO - 'base_work_dir': '/builds/slave/test', 04:41:44 INFO - 'blob_upload_branch': 'Firefox', 04:41:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:41:44 INFO - 'branch': 'Firefox', 04:41:44 INFO - 'buildbot_json_path': 'buildprops.json', 04:41:44 INFO - 'config_files': ('talos/mac_config.py',), 04:41:44 INFO - 'default_actions': ('clobber', 04:41:44 INFO - 'read-buildbot-config', 04:41:44 INFO - 'download-and-extract', 04:41:44 INFO - 'populate-webroot', 04:41:44 INFO - 'create-virtualenv', 04:41:44 INFO - 'install', 04:41:44 INFO - 'run-tests'), 04:41:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:41:44 INFO - 'download_minidump_stackwalk': True, 04:41:44 INFO - 'download_symbols': 'ondemand', 04:41:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:41:44 INFO - 'tooltool.py': '/tools/tooltool.py', 04:41:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:41:44 INFO - '/tools/misc-python/virtualenv.py')}, 04:41:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:41:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:41:44 INFO - 'gecko_profile': False, 04:41:44 INFO - 'gecko_profile_interval': 0, 04:41:44 INFO - 'installer_path': 'installer.exe', 04:41:44 INFO - 'log_level': 'info', 04:41:44 INFO - 'log_name': 'talos', 04:41:44 INFO - 'log_to_console': True, 04:41:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:41:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:41:44 INFO - 'opt_config_files': (), 04:41:44 INFO - 'pip_index': False, 04:41:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:41:44 INFO - 'cmd': ('bash', 04:41:44 INFO - '-c', 04:41:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:41:44 INFO - 'enabled': True, 04:41:44 INFO - 'halt_on_failure': False, 04:41:44 INFO - 'name': 'check_screen_resolution'},), 04:41:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:41:44 INFO - 'cmd': ('bash', 04:41:44 INFO - '-c', 04:41:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:41:44 INFO - 'enabled': True, 04:41:44 INFO - 'halt_on_failure': False, 04:41:44 INFO - 'name': 'check_screen_resolution'},), 04:41:44 INFO - 'run_cmd_checks_enabled': True, 04:41:44 INFO - 'suite': 'dromaeojs-e10s', 04:41:44 INFO - 'system_bits': '32', 04:41:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:41:44 INFO - 'title': 't-yosemite-r7-0165', 04:41:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:41:44 INFO - 'use_talos_json': True, 04:41:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:41:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:41:44 INFO - 'work_dir': 'build'} 04:41:44 INFO - [mozharness: 2017-05-06 11:41:44.798355Z] Running clobber step. 04:41:44 INFO - Running pre-action listener: _resource_record_pre_action 04:41:44 INFO - Running main action method: clobber 04:41:44 INFO - rmtree: /builds/slave/test/build 04:41:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:41:47 INFO - Running post-action listener: _resource_record_post_action 04:41:47 INFO - [mozharness: 2017-05-06 11:41:47.092366Z] Finished clobber step (success) 04:41:47 INFO - [mozharness: 2017-05-06 11:41:47.092517Z] Running read-buildbot-config step. 04:41:47 INFO - Running pre-action listener: _resource_record_pre_action 04:41:47 INFO - Running main action method: read_buildbot_config 04:41:47 INFO - Using buildbot properties: 04:41:47 INFO - { 04:41:47 INFO - "project": "", 04:41:47 INFO - "product": "firefox", 04:41:47 INFO - "script_repo_revision": "production", 04:41:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:41:47 INFO - "repository": "", 04:41:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:41:47 INFO - "buildid": "20170506030204", 04:41:47 INFO - "stage_platform": "macosx64", 04:41:47 INFO - "basedir": "/builds/slave/test", 04:41:47 INFO - "nightly_build": "True", 04:41:47 INFO - "buildnumber": 22, 04:41:47 INFO - "slavename": "t-yosemite-r7-0165", 04:41:47 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:41:47 INFO - "platform": "yosemite_r7", 04:41:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:41:47 INFO - "branch": "mozilla-central", 04:41:47 INFO - "repo_path": "mozilla-central", 04:41:47 INFO - "revision": "37a5b7f6f101df2eb292b1b6baaf1540c9920e20", 04:41:47 INFO - "pgo_build": "False", 04:41:47 INFO - "builduid": "905116b528b2426f8a0f55bff1cfb8de", 04:41:47 INFO - "slavebuilddir": "test" 04:41:47 INFO - } 04:41:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:41:47 INFO - Running post-action listener: _resource_record_post_action 04:41:47 INFO - [mozharness: 2017-05-06 11:41:47.096831Z] Finished read-buildbot-config step (success) 04:41:47 INFO - [mozharness: 2017-05-06 11:41:47.096990Z] Running download-and-extract step. 04:41:47 INFO - Running pre-action listener: _resource_record_pre_action 04:41:47 INFO - Running main action method: download_and_extract 04:41:47 INFO - mkdir: /builds/slave/test/build/tests 04:41:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:41:47 INFO - trying https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:41:47 INFO - Downloading https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:41:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:41:48 INFO - Downloaded 1325 bytes. 04:41:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:41:48 INFO - Using the following test package requirements: 04:41:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:41:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:41:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:41:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:41:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'jsshell-mac.zip'], 04:41:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:41:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:41:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:41:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:41:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:41:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:41:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:41:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:41:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:41:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:41:48 INFO - Fetch https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:41:49 INFO - Content-Length response header: 52702879 04:41:49 INFO - Bytes received: 52702879 04:42:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:42:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:42:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:42:06 INFO - Fetch https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:42:07 INFO - Content-Length response header: 52702879 04:42:07 INFO - Bytes received: 52702879 04:42:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:42:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:42:23 INFO - Fetch https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:42:25 INFO - Content-Length response header: 14408697 04:42:25 INFO - Bytes received: 14408697 04:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:25 INFO - trying https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:42:25 INFO - Downloading https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:42:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:42:27 INFO - Downloaded 70963547 bytes. 04:42:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:42:27 INFO - mkdir: /builds/slave/test/properties 04:42:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:42:27 INFO - Writing to file /builds/slave/test/properties/build_url 04:42:27 INFO - Contents: 04:42:27 INFO - build_url:https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:42:28 INFO - Running post-action listener: _resource_record_post_action 04:42:28 INFO - Running post-action listener: set_extra_try_arguments 04:42:28 INFO - [mozharness: 2017-05-06 11:42:28.138742Z] Finished download-and-extract step (success) 04:42:28 INFO - [mozharness: 2017-05-06 11:42:28.138866Z] Running populate-webroot step. 04:42:28 INFO - Running pre-action listener: _resource_record_pre_action 04:42:28 INFO - Running main action method: populate_webroot 04:42:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:42:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:42:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:42:28 INFO - '--tppagecycles', 04:42:28 INFO - '7'], 04:42:28 INFO - 'tests': ['tsvgm']}}, 04:42:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:42:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:42:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:42:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:42:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:42:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['damp', 'tps']}, 04:42:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'tests': ['damp', 'tps']}, 04:42:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['dromaeo_dom']}, 04:42:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:42:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:42:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:42:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['a11yr', 04:42:28 INFO - 'ts_paint', 04:42:28 INFO - 'tpaint', 04:42:28 INFO - 'sessionrestore', 04:42:28 INFO - 'sessionrestore_no_auto_restore', 04:42:28 INFO - 'tabpaint']}, 04:42:28 INFO - 'other-e10s': {'tests': ['a11yr', 04:42:28 INFO - 'ts_paint', 04:42:28 INFO - 'tpaint', 04:42:28 INFO - 'sessionrestore', 04:42:28 INFO - 'sessionrestore_no_auto_restore', 04:42:28 INFO - 'tabpaint']}, 04:42:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:42:28 INFO - 'ts_paint', 04:42:28 INFO - 'tpaint', 04:42:28 INFO - 'sessionrestore', 04:42:28 INFO - 'sessionrestore_no_auto_restore', 04:42:28 INFO - 'tabpaint']}, 04:42:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:42:28 INFO - 'ts_paint', 04:42:28 INFO - 'tpaint', 04:42:28 INFO - 'sessionrestore', 04:42:28 INFO - 'sessionrestore_no_auto_restore', 04:42:28 INFO - 'tabpaint']}, 04:42:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['a11yr', 04:42:28 INFO - 'ts_paint', 04:42:28 INFO - 'tpaint', 04:42:28 INFO - 'sessionrestore', 04:42:28 INFO - 'sessionrestore_no_auto_restore', 04:42:28 INFO - 'tabpaint']}, 04:42:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['a11yr', 04:42:28 INFO - 'ts_paint', 04:42:28 INFO - 'tpaint', 04:42:28 INFO - 'sessionrestore', 04:42:28 INFO - 'sessionrestore_no_auto_restore', 04:42:28 INFO - 'tabpaint']}, 04:42:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:42:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:42:28 INFO - 'bloom_basic_ref']}, 04:42:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['tsvgx', 04:42:28 INFO - 'tsvgr_opacity', 04:42:28 INFO - 'tart', 04:42:28 INFO - 'tscrollx', 04:42:28 INFO - 'cart', 04:42:28 INFO - 'tsvg_static']}, 04:42:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:42:28 INFO - 'tsvgr_opacity', 04:42:28 INFO - 'tart', 04:42:28 INFO - 'tscrollx', 04:42:28 INFO - 'cart', 04:42:28 INFO - 'tsvg_static']}, 04:42:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'talos_options': ['--disable-e10s'], 04:42:28 INFO - 'tests': ['tp5o']}, 04:42:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:42:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'talos_options': ['--disable-e10s', 04:42:28 INFO - '--xperf_path', 04:42:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:28 INFO - 'tests': ['tp5n']}, 04:42:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:42:28 INFO - 'talos_options': ['--xperf_path', 04:42:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:42:28 INFO - 'tests': ['tp5n']}}, 04:42:28 INFO - 'talos.zip': {'path': '', 04:42:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:42:28 INFO - Running post-action listener: _resource_record_post_action 04:42:28 INFO - [mozharness: 2017-05-06 11:42:28.157460Z] Finished populate-webroot step (success) 04:42:28 INFO - [mozharness: 2017-05-06 11:42:28.157600Z] Running create-virtualenv step. 04:42:28 INFO - Running pre-action listener: _resource_record_pre_action 04:42:28 INFO - Running main action method: create_virtualenv 04:42:28 INFO - Creating virtualenv /builds/slave/test/build/venv 04:42:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:42:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:42:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:42:28 INFO - Using real prefix '/tools/python27' 04:42:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:42:29 INFO - Installing distribute.............................................................................................................................................................................................done. 04:42:31 INFO - Installing pip.................done. 04:42:31 INFO - Return code: 0 04:42:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:42:32 INFO - Reading from file tmpfile_stdout 04:42:32 INFO - Output received: 04:42:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:42:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42: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', 'pip>=1.5'] in /builds/slave/test/build 04:42: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 pip>=1.5 04:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:32 INFO - 'HOME': '/Users/cltbld', 04:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:32 INFO - 'LOGNAME': 'cltbld', 04:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:32 INFO - 'MOZ_NO_REMOTE': '1', 04:42:32 INFO - 'NO_EM_RESTART': '1', 04:42:32 INFO - 'PAGER': '/bin/cat', 04:42:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:32 INFO - 'PWD': '/builds/slave/test', 04:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:32 INFO - 'SHELL': '/bin/bash', 04:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:32 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:32 INFO - 'USER': 'cltbld', 04:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:32 INFO - 'XPC_FLAGS': '0x0', 04:42:32 INFO - 'XPC_SERVICE_NAME': '0', 04:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:42:32 INFO - Cleaning up... 04:42:32 INFO - Return code: 0 04:42:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:42: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 psutil>=3.1.1 04:42:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:32 INFO - 'HOME': '/Users/cltbld', 04:42:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:32 INFO - 'LOGNAME': 'cltbld', 04:42:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:32 INFO - 'MOZ_NO_REMOTE': '1', 04:42:32 INFO - 'NO_EM_RESTART': '1', 04:42:32 INFO - 'PAGER': '/bin/cat', 04:42:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:32 INFO - 'PWD': '/builds/slave/test', 04:42:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:32 INFO - 'SHELL': '/bin/bash', 04:42:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:32 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:32 INFO - 'USER': 'cltbld', 04:42:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:32 INFO - 'XPC_FLAGS': '0x0', 04:42:32 INFO - 'XPC_SERVICE_NAME': '0', 04:42:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:32 INFO - Downloading/unpacking psutil>=3.1.1 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:42:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:35 INFO - Installing collected packages: psutil 04:42:35 INFO - Running setup.py install for psutil 04:42:35 INFO - building 'psutil._psutil_osx' extension 04:42:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:42:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:42:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:42:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:42:35 INFO - building 'psutil._psutil_posix' extension 04:42:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:42:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:42:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:42:36 INFO - ^ 04:42:36 INFO - 1 warning generated. 04:42:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:42:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:42:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:42:36 INFO - Successfully installed psutil 04:42:36 INFO - Cleaning up... 04:42:36 INFO - Return code: 0 04:42:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:42:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:36 INFO - 'HOME': '/Users/cltbld', 04:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:36 INFO - 'LOGNAME': 'cltbld', 04:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:36 INFO - 'MOZ_NO_REMOTE': '1', 04:42:36 INFO - 'NO_EM_RESTART': '1', 04:42:36 INFO - 'PAGER': '/bin/cat', 04:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:36 INFO - 'PWD': '/builds/slave/test', 04:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:36 INFO - 'SHELL': '/bin/bash', 04:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:36 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:36 INFO - 'USER': 'cltbld', 04:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:36 INFO - 'XPC_FLAGS': '0x0', 04:42:36 INFO - 'XPC_SERVICE_NAME': '0', 04:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:42:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:42:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:42:38 INFO - Installing collected packages: mozsystemmonitor 04:42:38 INFO - Running setup.py install for mozsystemmonitor 04:42:38 INFO - Successfully installed mozsystemmonitor 04:42:38 INFO - Cleaning up... 04:42:38 INFO - Return code: 0 04:42:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:38 INFO - 'HOME': '/Users/cltbld', 04:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:38 INFO - 'LOGNAME': 'cltbld', 04:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:38 INFO - 'MOZ_NO_REMOTE': '1', 04:42:38 INFO - 'NO_EM_RESTART': '1', 04:42:38 INFO - 'PAGER': '/bin/cat', 04:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:38 INFO - 'PWD': '/builds/slave/test', 04:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:38 INFO - 'SHELL': '/bin/bash', 04:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:38 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:38 INFO - 'USER': 'cltbld', 04:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:38 INFO - 'XPC_FLAGS': '0x0', 04:42:38 INFO - 'XPC_SERVICE_NAME': '0', 04:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:38 INFO - Downloading/unpacking jsonschema==2.5.1 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:42:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:42:40 INFO - warning: no files found matching '*.txt' 04:42:40 INFO - no previously-included directories found matching 'build' 04:42:40 INFO - no previously-included directories found matching 'dist' 04:42:40 INFO - no previously-included directories found matching '.git*' 04:42:40 INFO - Installing collected packages: jsonschema, functools32 04:42:40 INFO - Running setup.py install for functools32 04:42:40 INFO - warning: no files found matching '*.txt' 04:42:40 INFO - no previously-included directories found matching 'build' 04:42:40 INFO - no previously-included directories found matching 'dist' 04:42:40 INFO - no previously-included directories found matching '.git*' 04:42:40 INFO - Successfully installed jsonschema functools32 04:42:40 INFO - Cleaning up... 04:42:40 INFO - Return code: 0 04:42:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:40 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:40 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:42:40 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 functools32==3.2.3-2 04:42:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:40 INFO - 'HOME': '/Users/cltbld', 04:42:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:40 INFO - 'LOGNAME': 'cltbld', 04:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:40 INFO - 'MOZ_NO_REMOTE': '1', 04:42:40 INFO - 'NO_EM_RESTART': '1', 04:42:40 INFO - 'PAGER': '/bin/cat', 04:42:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:40 INFO - 'PWD': '/builds/slave/test', 04:42:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:40 INFO - 'SHELL': '/bin/bash', 04:42:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:40 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:40 INFO - 'USER': 'cltbld', 04:42:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:40 INFO - 'XPC_FLAGS': '0x0', 04:42:40 INFO - 'XPC_SERVICE_NAME': '0', 04:42:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:42:40 INFO - Cleaning up... 04:42:40 INFO - Return code: 0 04:42:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:40 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 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:42:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:40 INFO - 'HOME': '/Users/cltbld', 04:42:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:40 INFO - 'LOGNAME': 'cltbld', 04:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:40 INFO - 'MOZ_NO_REMOTE': '1', 04:42:40 INFO - 'NO_EM_RESTART': '1', 04:42:40 INFO - 'PAGER': '/bin/cat', 04:42:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:40 INFO - 'PWD': '/builds/slave/test', 04:42:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:40 INFO - 'SHELL': '/bin/bash', 04:42:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:40 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:40 INFO - 'USER': 'cltbld', 04:42:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:40 INFO - 'XPC_FLAGS': '0x0', 04:42:40 INFO - 'XPC_SERVICE_NAME': '0', 04:42:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:40 INFO - Downloading/unpacking blobuploader==1.2.4 04:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:42:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:42:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:42:43 INFO - Downloading docopt-0.6.1.tar.gz 04:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:42:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:42:43 INFO - Running setup.py install for blobuploader 04:42:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:42:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:42:43 INFO - Running setup.py install for requests 04:42:43 INFO - Running setup.py install for docopt 04:42:44 INFO - Successfully installed blobuploader requests docopt 04:42:44 INFO - Cleaning up... 04:42:44 INFO - Return code: 0 04:42:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:44 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 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:44 INFO - 'HOME': '/Users/cltbld', 04:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:44 INFO - 'LOGNAME': 'cltbld', 04:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:44 INFO - 'MOZ_NO_REMOTE': '1', 04:42:44 INFO - 'NO_EM_RESTART': '1', 04:42:44 INFO - 'PAGER': '/bin/cat', 04:42:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:44 INFO - 'PWD': '/builds/slave/test', 04:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:44 INFO - 'SHELL': '/bin/bash', 04:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:44 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:44 INFO - 'USER': 'cltbld', 04:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:44 INFO - 'XPC_FLAGS': '0x0', 04:42:44 INFO - 'XPC_SERVICE_NAME': '0', 04:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-SPft90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-GcXNQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-nu8Cc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-RVicx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-qUBIPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-Uied6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:44 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-98jeqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-KZkPBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-4J28Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-JsSFRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-lFDn2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-HmKvTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-YnlrIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-L4OLE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-HVUjjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:45 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-oF6_Nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:46 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-58gRFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:42:46 INFO - Running setup.py install for manifestparser 04:42:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:42:46 INFO - Running setup.py install for mozcrash 04:42:46 INFO - Running setup.py install for mozdebug 04:42:46 INFO - Running setup.py install for mozdevice 04:42:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:42:46 INFO - Running setup.py install for mozfile 04:42:46 INFO - Running setup.py install for mozhttpd 04:42:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:42:46 INFO - Running setup.py install for mozinfo 04:42:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozInstall 04:42:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozleak 04:42:47 INFO - Running setup.py install for mozlog 04:42:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for moznetwork 04:42:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:42:47 INFO - Running setup.py install for mozprocess 04:42:47 INFO - Running setup.py install for mozprofile 04:42:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Running setup.py install for mozrunner 04:42:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Running setup.py install for mozscreenshot 04:42:48 INFO - Running setup.py install for moztest 04:42:48 INFO - Running setup.py install for mozversion 04:42:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:42:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:42:48 INFO - Cleaning up... 04:42:48 INFO - Return code: 0 04:42:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:48 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 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:42:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:48 INFO - 'HOME': '/Users/cltbld', 04:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:48 INFO - 'LOGNAME': 'cltbld', 04:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:48 INFO - 'MOZ_NO_REMOTE': '1', 04:42:48 INFO - 'NO_EM_RESTART': '1', 04:42:48 INFO - 'PAGER': '/bin/cat', 04:42:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:48 INFO - 'PWD': '/builds/slave/test', 04:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:48 INFO - 'SHELL': '/bin/bash', 04:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:48 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:48 INFO - 'USER': 'cltbld', 04:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:48 INFO - 'XPC_FLAGS': '0x0', 04:42:48 INFO - 'XPC_SERVICE_NAME': '0', 04:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:42:48 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-JEDGyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-z6DVTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-itT0Pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-1WyNWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-a9porJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-ZmDKUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-VJeHx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-q_rH1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-dvXsNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:42:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:42:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:42:49 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-OG36dR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-kcqIZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-RKNMSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-6mnPwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-xaEj8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-ZvIbF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-32pBfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:42:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:42:50 INFO - Running setup.py (path:/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/pip-FTbDfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:42:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:42:50 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:42:50 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:42:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42: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 04:42:52 INFO - Downloading blessings-1.6.tar.gz 04:42:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:42:52 INFO - Installing collected packages: blessings 04:42:52 INFO - Running setup.py install for blessings 04:42:52 INFO - Successfully installed blessings 04:42:52 INFO - Cleaning up... 04:42:52 INFO - Return code: 0 04:42:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:42:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:52 INFO - Reading from file tmpfile_stdout 04:42:52 INFO - Current package versions: 04:42:52 INFO - blessings == 1.6 04:42:52 INFO - blobuploader == 1.2.4 04:42:52 INFO - docopt == 0.6.1 04:42:52 INFO - functools32 == 3.2.3-2 04:42:52 INFO - jsonschema == 2.5.1 04:42:52 INFO - manifestparser == 1.1 04:42:52 INFO - mozInstall == 1.12 04:42:52 INFO - mozcrash == 1.0 04:42:52 INFO - mozdebug == 0.1 04:42:52 INFO - mozdevice == 0.50 04:42:52 INFO - mozfile == 1.2 04:42:52 INFO - mozhttpd == 0.7 04:42:52 INFO - mozinfo == 0.9 04:42:52 INFO - mozleak == 0.1 04:42:52 INFO - mozlog == 3.4 04:42:52 INFO - moznetwork == 0.27 04:42:52 INFO - mozprocess == 0.25 04:42:52 INFO - mozprofile == 0.28 04:42:52 INFO - mozrunner == 6.13 04:42:52 INFO - mozscreenshot == 0.1 04:42:52 INFO - mozsystemmonitor == 0.3 04:42:52 INFO - moztest == 0.8 04:42:52 INFO - mozversion == 1.4 04:42:52 INFO - psutil == 3.1.1 04:42:52 INFO - requests == 1.2.3 04:42:52 INFO - wsgiref == 0.1.2 04:42:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:42:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:52 INFO - 'HOME': '/Users/cltbld', 04:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:52 INFO - 'LOGNAME': 'cltbld', 04:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:52 INFO - 'MOZ_NO_REMOTE': '1', 04:42:52 INFO - 'NO_EM_RESTART': '1', 04:42:52 INFO - 'PAGER': '/bin/cat', 04:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:52 INFO - 'PWD': '/builds/slave/test', 04:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:52 INFO - 'SHELL': '/bin/bash', 04:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:52 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:52 INFO - 'USER': 'cltbld', 04:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:52 INFO - 'XPC_FLAGS': '0x0', 04:42:52 INFO - 'XPC_SERVICE_NAME': '0', 04:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:42:52 INFO - Cleaning up... 04:42:52 INFO - Return code: 0 04:42:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:42:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--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 0x104b50b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b53768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff29b5c10f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, '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.6UwdKrlCHf/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.DqVKeennOS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:42:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:42:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:42:52 INFO - 'HOME': '/Users/cltbld', 04:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:42:52 INFO - 'LOGNAME': 'cltbld', 04:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:52 INFO - 'MOZ_NO_REMOTE': '1', 04:42:52 INFO - 'NO_EM_RESTART': '1', 04:42:52 INFO - 'PAGER': '/bin/cat', 04:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:52 INFO - 'PWD': '/builds/slave/test', 04:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:42:52 INFO - 'SHELL': '/bin/bash', 04:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:42:52 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:42:52 INFO - 'USER': 'cltbld', 04:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:52 INFO - 'XPC_FLAGS': '0x0', 04:42:52 INFO - 'XPC_SERVICE_NAME': '0', 04:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:42:53 INFO - Cleaning up... 04:42:53 INFO - Return code: 0 04:42:53 INFO - Running post-action listener: _resource_record_post_action 04:42:53 INFO - Running post-action listener: _start_resource_monitoring 04:42:53 INFO - Starting resource monitoring. 04:42:53 INFO - [mozharness: 2017-05-06 11:42:53.276508Z] Finished create-virtualenv step (success) 04:42:53 INFO - [mozharness: 2017-05-06 11:42:53.277329Z] Running install step. 04:42:53 INFO - Running pre-action listener: _resource_record_pre_action 04:42:53 INFO - Running main action method: install 04:42:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:42:53 INFO - Reading from file tmpfile_stdout 04:42:53 INFO - Detecting whether we're running mozinstall >=1.0... 04:42:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:42:53 INFO - Reading from file tmpfile_stdout 04:42:53 INFO - Output received: 04:42:53 INFO - Usage: mozinstall [options] installer 04:42:53 INFO - Options: 04:42:53 INFO - -h, --help show this help message and exit 04:42:53 INFO - -d DEST, --destination=DEST 04:42:53 INFO - Directory to install application into. [default: 04:42:53 INFO - "/builds/slave/test"] 04:42:53 INFO - --app=APP Application being installed. [default: firefox] 04:42:53 INFO - mkdir: /builds/slave/test/build/application 04:42:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:43:13 INFO - Reading from file tmpfile_stdout 04:43:13 INFO - Output received: 04:43:13 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:43:13 INFO - Running post-action listener: _resource_record_post_action 04:43:13 INFO - [mozharness: 2017-05-06 11:43:13.272880Z] Finished install step (success) 04:43:13 INFO - [mozharness: 2017-05-06 11:43:13.273015Z] Running run-tests step. 04:43:13 INFO - Running pre-action listener: _resource_record_pre_action 04:43:13 INFO - Running main action method: run_tests 04:43:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:13 INFO - 2017-05-06 04:43:13.286 screenresolution[2029:20799] starting screenresolution argv=screenresolution get 04:43:13 INFO - 2017-05-06 04:43:13.289 screenresolution[2029:20799] Display 0: 1600x1200x32@60 04:43:13 INFO - 2017-05-06 04:43:13.294 screenresolution[2030:20801] starting screenresolution argv=screenresolution list 04:43:13 INFO - Available Modes on Display 0 04:43:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:43:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:43:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:43:13 INFO - Intel Iris: 04:43:13 INFO - Chipset Model: Intel Iris 04:43:13 INFO - Type: GPU 04:43:13 INFO - Bus: Built-In 04:43:13 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:13 INFO - Vendor: Intel (0x8086) 04:43:13 INFO - Device ID: 0x0a2e 04:43:13 INFO - Revision ID: 0x0009 04:43:13 INFO - Displays: 04:43:13 INFO - Display: 04:43:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:43:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:13 INFO - Display Serial Number: 1600x1200 60 04:43:13 INFO - Main Display: Yes 04:43:13 INFO - Mirror: Off 04:43:13 INFO - Online: Yes 04:43:13 INFO - Rotation: Supported 04:43:13 INFO - Return code: 0 04:43:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:43:13 INFO - Python 2.7.3 04:43:13 INFO - Return code: 0 04:43:13 INFO - grabbing minidump binary from tooltool 04:43:13 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:43:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10492ed30>, '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 0x104854db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104923be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:43:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:43:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:43:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:43:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:43:13 INFO - Return code: 0 04:43:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:43:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:43:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:43:13 INFO - ENV: RUST_BACKTRACE is now 1 04:43:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:43:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:43:13 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:43:13 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0165 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6UwdKrlCHf/Render', 04:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:43:13 INFO - 'HOME': '/Users/cltbld', 04:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:43:13 INFO - 'LOGNAME': 'cltbld', 04:43:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:43:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:13 INFO - 'MOZ_NO_REMOTE': '1', 04:43:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:43:13 INFO - 'NO_EM_RESTART': '1', 04:43:13 INFO - 'PAGER': '/bin/cat', 04:43:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:13 INFO - 'PWD': '/builds/slave/test', 04:43:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:43:13 INFO - 'RUST_BACKTRACE': '1', 04:43:13 INFO - 'SHELL': '/bin/bash', 04:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DqVKeennOS/Listeners', 04:43:13 INFO - 'TMPDIR': '/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/', 04:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:43:13 INFO - 'USER': 'cltbld', 04:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:13 INFO - 'XPC_FLAGS': '0x0', 04:43:13 INFO - 'XPC_SERVICE_NAME': '0', 04:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:43:13 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:43:13 INFO - mozversion application_buildid: 20170506030204 04:43:13 INFO - mozversion application_changeset: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 04:43:13 INFO - mozversion application_display_name: Nightly 04:43:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:43:13 INFO - mozversion application_name: Firefox 04:43:13 INFO - mozversion application_remotingname: firefox 04:43:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:43:13 INFO - mozversion application_vendor: Mozilla 04:43:13 INFO - mozversion application_version: 55.0a1 04:43:13 INFO - mozversion platform_buildid: 20170506030204 04:43:13 INFO - mozversion platform_changeset: 37a5b7f6f101df2eb292b1b6baaf1540c9920e20 04:43:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:43:13 INFO - mozversion platform_version: 55.0a1 04:43:13 INFO - using testdate: 1494070993 04:43:13 INFO - actual date: 1494070993 04:43:13 INFO - starting webserver on 'localhost:53090' 04:43:13 INFO - SUITE-START | Running 2 tests 04:43:13 INFO - TEST-START | dromaeo_css 04:43:13 INFO - operating with platform_type : mac_ 04:43:13 INFO - Initialising browser for dromaeo_css test... 04:43:13 INFO - TEST-INFO | started process 2040 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmpIH0bk3/profile http://localhost:53090/getInfo.html) 04:43:16 INFO - PID 2040 | 2017-05-06 04:43:16.611 plugin-container[2043:20976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a43, name = 'com.apple.tsm.portname' 04:43:16 INFO - PID 2040 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:16 INFO - PID 2040 | 2017-05-06 04:43:16.612 plugin-container[2043:20976] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1f, name = 'com.apple.CFPasteboardClient' 04:43:16 INFO - PID 2040 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:16 INFO - PID 2040 | 2017-05-06 04:43:16.612 plugin-container[2043:20976] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:16 INFO - PID 2040 | __metrics Screen width/height:1600/1200 04:43:16 INFO - PID 2040 | colorDepth:24 04:43:16 INFO - PID 2040 | Browser inner width/height: 1024/691 04:43:16 INFO - PID 2040 | __metrics 04:43:17 INFO - TEST-INFO | 2040: exit 0 04:43:17 INFO - Browser initialized. 04:43:17 INFO - Running cycle 1/1 for dromaeo_css test... 04:43:17 INFO - TEST-INFO | started process 2045 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmpIH0bk3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:20 INFO - PID 2045 | 2017-05-06 04:43:20.455 plugin-container[2046:21255] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 04:43:20 INFO - PID 2045 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:20 INFO - PID 2045 | 2017-05-06 04:43:20.456 plugin-container[2046:21255] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 04:43:20 INFO - PID 2045 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:20 INFO - PID 2045 | 2017-05-06 04:43:20.456 plugin-container[2046:21255] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:20 INFO - PID 2045 | 2017-05-06 04:43:20.974 plugin-container[2047:21296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 04:43:20 INFO - PID 2045 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:20 INFO - PID 2045 | 2017-05-06 04:43:20.975 plugin-container[2047:21296] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 04:43:20 INFO - PID 2045 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:43:20 INFO - PID 2045 | 2017-05-06 04:43:20.975 plugin-container[2047:21296] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:43:30 INFO - PID 2045 | RSS: Main: 234168320 04:43:30 INFO - PID 2045 | 04:46:34 INFO - PID 2045 | Cycle 1(1): loaded http://localhost:53090/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53090/tests/dromaeo/cssquery-ext.html) 04:46:36 INFO - PID 2045 | RSS: Main: 250466304 04:46:36 INFO - PID 2045 | 04:49:38 INFO - PID 2045 | Cycle 1(1): loaded http://localhost:53090/tests/dromaeo/cssquery-ext.html (next: http://localhost:53090/tests/dromaeo/cssquery-jquery.html) 04:49:38 INFO - PID 2045 | RSS: Main: 257114112 04:49:38 INFO - PID 2045 | 04:52:40 INFO - PID 2045 | Cycle 1(1): loaded http://localhost:53090/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53090/tests/dromaeo/cssquery-mootools.html) 04:52:40 INFO - PID 2045 | RSS: Main: 266084352 04:52:40 INFO - PID 2045 | 04:55:42 INFO - PID 2045 | Cycle 1(1): loaded http://localhost:53090/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53090/tests/dromaeo/cssquery-prototype.html) 04:55:43 INFO - PID 2045 | RSS: Main: 258228224 04:55:43 INFO - PID 2045 | 04:58:46 INFO - PID 2045 | Cycle 1(1): loaded http://localhost:53090/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53090/tests/dromaeo/cssquery-yui.html) 04:58:46 INFO - PID 2045 | RSS: Main: 262467584 04:58:46 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | Cycle 1(1): loaded http://localhost:53090/tests/dromaeo/cssquery-yui.html (next: http://localhost:53090/tests/dromaeo/cssquery-dojo.html) 05:01:48 INFO - PID 2045 | RSS: Main: 261685248 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | __start_tp_report 05:01:48 INFO - PID 2045 | _x_x_mozilla_page_load 05:01:48 INFO - PID 2045 | _x_x_mozilla_page_load_details 05:01:48 INFO - PID 2045 | |i|pagename|runs| 05:01:48 INFO - PID 2045 | |0;dojo.html;3539.946900796488;3604.6395360463957;3611.927761444773;3649.1606930405997;3663.3222853772054;4734.43186817582;6150.384961503847;6152.8461788455315;6192.380761923805;6268.68656567171;6189.412005859447;6199.535034872391;6247.312795592493;6255.937440625592;6272.592281501699;4953.133201689708;5256.737163141846;5359.062164121283;5418.4039755626845;5436.646617969829;5046.293518907356;5526.530244929186;5616.101423772197;5624.100143976964;5657.490825825675;5507.008738427122;5522.116461366182;5562.916556251659;5563.944360556424;5598.384177740445;4029.8388064477385;5313.830957189412;5524.861878453031;5540.50135487804;5551.694656793875;5448.828501872077;5467.371252305996;5514.310711161105;5515.834524964217;5530.972345138289;4617.907641847144;6149.800788846177;6177.62934223948;6228.346023667495;6234.688265586702;6763.492738044666;6773.288804675487;6790.728370865159;6797.456203503708;6804.183497980274;5744.712764361807;6893.241743408221;6897.379235868746;6905.551139175937;6905.827354316132;6190.907136392958;6220.409061139185;6293.370662933761;6367.777127800551;6371.585846919982;7019.403350715246;7049.753258636026;7054.2240353561065;7062.293770622897;7070.36366726997;5846.912296315559;6440.4847612190915;6488.545801793829;6507.186601674791;6617.636030018299;6641.933580664228;6816.931830681729;6851.931480685229;6856.828579285558;6857.657117144123;6507.219133703986;6800.387965083166;6802.3537763912345;6802.4217941474535;6827.180738311435;6640.335966403418;6646.767363142267;6687.498437617198;6711.060451536789;6811.318868113149;5367.463253674601;5377.892442151135;5393.7572809223675;5404.32445944257;5429.945700542944;4696.38946936896;4779.996200797802;4788.138135135709;4799.280107983761;4847.854564363075;5386.407495175527;6242.5942313750365;6249.968750156311;6254.781082662085;6380.0748891410485;6837.829054273683;6854.760083397157;6929.549579277331;6933.5493192942295;6934.826629334206;5746.4253574643035;6163.96918015407;6468.482521398277;6487.6756162191705;6490.02649602554;5906.084556893688;5972.761089556456;6012.549058820606;6058.4244496772735;6085.6348619083;7055.294470553006;7070.505064645426;7093.751718689821;7101.147862256562;7136.3220494052985;6913.5506192097355;6927.272636373108;6940.9652951734915;6950.54821436605;6960.582365058112;7001.4748893834185;7056.15326160864;7155.212926577969;7168.462365322726;7192.352688258082;5583.888322233452;6482.57863238888;6542.54202205841;6682.966585167043;6763.188417389944;6913.481488888253;6917.13535808024;6925.376716095576;6928;6935.757248496278;5043.268726034631;6058.57589968698;6385.29761726219;6469.417752402306;6655.467562595078;6453.322401148;6507.089007539043;6509.088727578048;6510.7721229756735;6660.700268487833;6344.777932772331;6516.73933042692;6532.608043517214;6532.608043517404;6536.869262614816;6321.715522801577;6451.225852897683;6548.279689234085;6587.4071333580205;6675.465962723068;5471.23402723627;6466.321036291121;6515.641639709672;6531.608103513804;6536.509761767981 05:01:48 INFO - PID 2045 | |1;ext.html;645.883740926633;730.8428687832117;798.6086817459443;1106.2422240765072;1221.370993938122;35460.29079418413;36456.724014659456;36476;36492;36493.81753091231;36048.81975590125;36121.45817812735;36315.27369452616;36336.27327453449;36454.453183202204;7640.694372225118;7777.533347999138;8257.752267431986;8360.623771930248;8403.957980210122;8298.25315721585;8363.289120424772;8377.790555236106;8385.790355241106;8408.453450525696;8363.83272334553;8381.119982401851;8408.453450525696;8417.494950302971;8433.873491897626;8332.083470818205;8358.164183581654;8385.832283354359;8394.20255075773;8405.453645513053;8267.090620031791;8286.171382861665;8326.375521835887;8337.207965243353;8346.582670866432;36494.27011459783;36652.083697907765;36767.264654706756;36790;36954;3530.3115892400992;3561.8041007744564;3563.1804684922354;3563.8574457021687;3568.91077723059;30110;30364.937227197162;30472;30489.69510304913;30491.847540762377;3036.832974186419;3153.290509635314;3154.952675709866;3173.2066983254185;3179.9682003180133;196407.07185856346;218770;272913;297394;299260;46818.063638727035;46831.53168468272;47212.5278747215;47242.76378618085;47247.76376118166;40888;42875;46028.619141425814;48344.51655483471;49214;13311.800322995163;13907.791383129059;14049.086809357359;14053.71892562143;14061.859381406259;11456.312621242585;11546.422678866023;11558.768824623461;11566.537338506534;11596.072314214842;11080.836512166268;13137.408816603276;13255.536056237985;13265.403056862271;13284.202947823162;5931.317898441648;5943.108533719977;6002;6006.249218847644;6024.969875150597;944.8771659684197;957.6169532187181;970.2141265574766;974.2936371131015;1009.1573536097294;19385.903070484557;22207.55584888293;23248.95379707917;23361.532769344518;23655.526889462115;921.235374639048;956.4787190980932;1025.600015993763;1055.7096798380508;1095.1840878545534;9521.619135234652;11627.418629068512;11977.323174755542;12028.21816581937;12028.819567706492;287949;287968;289226;289672;289961;53472.73263633657;53489.73255133777;53642.19536706952;53739.462605374225;53805.46194538083;1487.5462983790048;1534.884883633732;1539.992300038515;1544.3282172255106;1557.688462307543;19321.806781932002;20331.796682033582;23552.52894942126;23801;23815;37561;48154;49019;49289.75355123202;49404.25893611527;209260;209751;210465;210751;211386.9430652837;22691.659625105305;28363.57454638141;31227.84386078055;31873.521897171096;32433.675663243066;33472;40814.1837163261;43025.3546196801;43103;43374.566254337056;29908.102756917327;30056.248593784454;30236.593138351098;30390.69609303967;30575.541366879068;29773.404531908807;29819.403611928075;30247.546286805275;30380.696193037787;30989.225269368446;35409.46885796663;36090.09774755547;36516.81741591275;36546.7208647696;36580.81709591435;28494.715052849206;29921.551176732803;30433.39133217279;30629.693703063575;30836.074917752503;22059.779402206415;23692.05231790778;24035.27894163178;24120;24205 05:01:48 INFO - PID 2045 | |2;jquery.html;427.9336702811065;434.1729006243106;434.35281430668294;449.438202247191;456.1834316573334;19368.709469357953;21099.789002109974;21546;21727.565448691017;21846.126154953803;18048.909755451205;22654.1487947049;23564.64653030206;23584;23852.284431467084;6145.385461453852;6288.591241569284;6331.050342448623;6344.080108384282;6365.936340636581;5859.560532960045;6128.8467788305425;6177.413145751147;6181.381861813805;6189.938100619003;5978.40215978403;6006.399360064002;6087.147799308101;6147.969260153693;6175.907361389561;6086.147939288503;6157.568970172089;6169.043798211262;6178.474829639441;6196.349383314755;5388.0570900092325;5399.784008639689;5422.945770542323;5472.562195024429;5486.366544058834;27052;27291.45417091667;27559.173224803086;28046.719532804615;28430.431391372058;2236.3626366485473;2302.769723027684;2310.1567927706433;2311.9422014449606;2313.953720925589;17440.953542787473;17585.944843309313;17756.289748410047;18218;18414.631707365777;2136.807687308134;2139.6148693235214;2175.510510135223;2187.0923566719794;2190.4304880731124;128062;151258.24370878184;153835;153943;154859;10396.764695590484;11260.774784504263;12243.08176886737;12812.80780788289;12831.615051548468;16208.270627821776;16349.918250408671;16355.427560035341;16369.427070052729;16386;8888;9093.54532273397;9105.408148470327;9159;9260.166585007382;7210.639468026683;8647.524386158822;8668.0898505656;8799.604017819214;8810.955945220232;6249.75004999002;7117.964410177916;7948.801279967932;8039.11080895989;8074.152214017444;2168.1435832845937;2168.241115609524;2180.269609680775;2188.245055455865;2377.5007248478014;943.8584212368199;969.9321047526746;970.3207754571687;980.2501086668705;1018.8624535687735;19871.900640496995;21182.78817211839;21749.34751957444;21825.23611673584;21840.017199226073;840.9537475438788;985.935914165577;989.8663680403198;1015.5125539740966;1029.3206483720708;9779.657711980046;11113.499892504857;11200.103991680646;11246.987771100641;11257.268277561932;355362;360618;423546;439933;440924;101816.49091754494;103057;109438;110887;113043.86956130498;1077.3966578715945;1124.791913496006;1145.7479354541988;1156.5951916829042;1159.7390587118;17202.741958870378;18053.279507574076;18169.18238679289;18195.272189112286;18396.632067358845;39576;51831;51880;52349.64426600736;52722.736386317825;72377.63811180911;72632.63683681548;73258.63370683113;73561;73972.94514042315;8374.120717324593;8887.066857980077;8891.066438024172;8894.555272236492;9085.77285567866;13330.800037999245;13751.79372309396;13785.655358616115;14013.789793152908;14061.36723847409;11675.007624351685;11725.296482211095;12041.337726424781;12116.636500904988;12249.387530623611;9391.765205869908;11235.382053986783;11295.3787541686;11306.038986685895;11315.284331760482;11837.704057398634;11948.163628545919;12159.635210943687;12224.38878056076;12245.816312755138;7410.814729631802;8433;8616.612252448529;8759.474431534129;8950.194482496607;11050.673919129435;11275.492602833056;11757.529698811952;11884.405779710807;12045.096617753528 05:01:48 INFO - PID 2045 | |3;mootools.html;1161.4889448642602;1205.7709035283297;1377.387062757073;1429.6354429620446;1433.3406632948843;9806.901930980688;10650.946745266283;11429.99999999998;11525.25085869419;11779.70550736232;11425.942870285659;11541.65375038748;11583.942080289607;11590.478428470718;11596.478158482867;2505.9624105638477;2547.700672656949;2550.591905295153;2551.3494059014924;2555.169569889784;2152.8923553822324;2463.088657196831;2502.724700282967;2504.1485894795765;2514.0823599386185;2453.472503411766;2479.789217916479;2481.3176376496517;2508.5610018246825;2514.0823599386185;2452.6443665668467;2473.332200305925;2500.7499250074848;2506.2982364937848;2509.3814489654255;2391.426057746128;2495.4510007798253;2503.4492411669403;2509.1719732488236;2514.8491090534626;9431.528423578862;11329.773404531863;11461;11475.540978360856;11498.080153587776;6472.352764723537;6637.767678131242;6705.061291419205;6712;6733.091032710619;4044.292248856438;4353.521112677603;4375.934360984587;4377.868663940087;4439.933400998988;1224.3205021213223;1244.4710997825887;1288.5103660608909;1291.8901893339078;1294.8899343555809;15664.765028524582;16224.188790560425;16288;16293.59266018347;16308.429204977887;5355.785768569213;5479.972600137054;5597.496225339658;5608.327000759935;5649.152627105967;11485.138614603939;11529.365884876412;11580.536778528765;11595.652130436101;11595.826062609067;6491.253505846888;6509.1863517060365;6512.023196520559;6516.869662606816;6525.249596296487;5876.500497457679;6053;6136;6218.751249950042;6254.843628909222;4293.355996600535;4349.652027837766;4391.099824535962;4393.6704747144095;4430.645548356124;1242.9813552796716;1264.6648638110908;1268.4989429175423;1278.3799857069314;1288;758.707897459471;776.2392855002016;784.6351446577369;786.4573444323513;801.5711594297023;6465.062565927914;6711.060451536789;6841.350071743176;6855;6909.861802763917;926.6756635177634;946.8674385586023;953.3421938862301;959.2853324273461;960.4573416019927;3515.5781306243416;3867.2265546890735;4123;4216;4218.578142185818;14112.647183820487;14453;14635.853641463662;14706.41174353014;14710.411583536752;14067.085639433404;14374.568762937128;14587;14587.781183282259;14617.707645847024;6277.497800176066;8450.408471407065;8556.786080348042;8565.828683426296;8583.527905965107;6064.211652485325;6514.73941042353;6702.698378572878;6758.256591774999;6927.930720692729;12365.876341236473;12922.806157907828;13157.736845262849;13198.60404187876;13243.536476223286;13036.804447933479;13475.663108421975;13538;13592.048556600943;13737.725245495234;7999.600069987612;9163.587638556148;9180.036096209802;9381.624735010522;9387.249020078514;10720.892791072301;10880.891191088305;10902.890971090188;10908.836367454653;10933.945330273298;7194.244604316681;8925.553722313729;8946.507942062988;9028.18746312835;9098.226650734761;7805.555972145171;8450.239478446723;8683.565821708762;8882.777930551529;8946.507942063248;7039.401650859734;8007.43947923663;8099.392545558989;8123.918760812317;8138.3082437993435;5977.462028417463;5985.221921150222;6072.90890636632;6124.846878828065;6154.476869466145;5015.498450154955;5976.491998180203;6148.692565371803;6175.814725558241;6242.812715618538 05:01:48 INFO - PID 2045 | |4;prototype.html;381.84535263218373;383.2277959910779;391.43046866808794;405.8011574328576;406.14506463893514;5374.301340825697;6315.11588377627;6387.658591695744;6398.232212134544;6454.451371633417;6092.634441933487;6226.43962043416;6261.906071408933;6266.216722909637;6272.717727702253;3780.2439512097553;4458.948883513588;4557.703749256304;4583.541645835423;4593.885152871188;4581.037982023779;4585.426821647294;4601.7469039203;4606.562376574237;4610.423697037871;3776.3761582519505;4540.864783804049;4553.5901768840795;4553.68124231304;4594.816207351702;3885.445821671326;4387.890302742425;4527.592516673515;4570.817167313304;4590.967032417712;3199.5840540729787;3200.543752592565;3846.6345697158727;3881.6894648428056;3896.8246428910484;5106.974465127651;6197;6198.659073750942;6222.844428889269;6234.314225435199;4422.292433210703;4507.414036175309;5371.248025276464;5398.757055932492;5440.646357986719;6353.744420730621;6436.259830119549;6589.209294884645;6651.268360480343;6703.296153903819;4245.74975263108;4375.78121093944;4380.912381752347;4385.978070109661;4390.560943905616;5988.640681559077;6937.375636192767;7015.123109611299;7091.787246382617;7164.176119746244;3898.6101389860787;3941.265843029058;4283.764392958355;4348.630181492883;4431.180231657154;4523.615492683159;4538.523455037173;4546.636269098464;4555.790441635804;4556.56468212512;5330.466953304716;5350.732463376816;5379.757910894019;5393;5482.506574408322;6732.427743641845;6757;6777.72889084441;6780.9150535915005;6819.795406137824;1527.4653871145233;1546.2577962578025;1552.1773460066183;1553.8834587405763;1558.932131489933;867.9566021698889;928.5635751196927;969.2246203037541;988.0761488008735;989.2432289298692;5615.971920140455;5713.4000929902595;6434.131392261985;6489.772857949949;6528.596351784389;6930.925706515498;7027.4729395294535;7038.6480675965995;7054.5061845671335;7057.717691292393;6355.650439225795;6402.36739631391;6406.327335629785;6423.518236132309;6455.419012288917;3223.7582181336493;3301.7783420134706;3677.2645470905927;3689.169936764207;3701.1672373715696;6249.875002499924;6706.899396509057;7071.974563688339;7195.352418282379;7262.564246145248;6785.355391237825;6910.44716422685;7178.138623365229;7191.280871912872;7222.241664625244;7076.150861896503;7214.170370407365;7262.491625586158;7264.237255088246;7336.486445948733;6101.633901965896;6778.8644227116165;6844.349786770249;7048.286409732393;7097.432205423657;6863.794086177422;6896.586204827727;6916.757913473004;6917.239103698488;6954.79135625932;6906.585604863724;7094.787156385317;7131.536450130725;7260.891086633599;7283.629822106694;4415.933760993524;4428.778561071869;4440.777961101868;4457.777111144365;4468.419105516262;6143.784967526115;6294.968525157345;6869.931300687129;6930.237673855972;6934.821080416241;5500.394956554695;6198.907016394848;6706.5640733352175;6760.1549806274215;6766.898496522457;5754.395788442153;6598.670066496752;6705.597664140168;6718.496112791462;6765.594064356154;5019.498050194951;6245.344238854855;6255.40573645503;6431.389018043278;6640.443429279666;6388.744450221939;6430.135260774323;6782.660866956731;6814.182298124257;6846.7945961621235;5930.436608522184;6653.600783952978;6657.866842663217;6665.400113989765;6670.499712521483 05:01:48 INFO - PID 2045 | |5;yui.html;679.8572299817038;814.5723495165034;827.5283088639478;857.9485230886147;867.796067924038;15826;16404.75392869108;16426.83573164268;18521.722174167397;19149;15203.619909502268;15710.371585136609;17836.286548538075;18173.273069077222;18372.540686482844;979.1530326267798;987.4075554667214;989.8465737810651;999.6201443451479;1000.8899021107673;1011.6408674920408;1012.0233974214881;1013.6502906497246;1015.1624909449697;1015.4161357219591;1046.6336782126234;1063.377923914511;1068.433730123036;1068.754186537097;1071.234067641636;1038.2887721910515;1046.3146638951498;1060.6234786650743;1060.7401186709228;1062.7715041266126;762.9465937384385;776.5301992294637;791.6398038892348;792.5522080024767;795.7732046366758;13683.452661893612;17193.140342982802;17295.048772317517;17301.826981730148;17313.826861731348;12649.873501264961;15468.994515356466;16094.839051609451;16583.751243731353;16810.24353904065;13474.461021559224;14236.857631423656;14279.143251404948;14368;14370.20963846988;3474.3919814032442;3489.284696637183;3510.087377281925;3561.94657080144;3572.6427357264324;100936;109328.36007459974;118542.40728796387;119648;121270;11482.48328825205;12017.759644807054;13517.932410337886;13669.111507751964;13720.245386503839;21083.68374474384;25222.495550088894;25564.360890977503;25944;25953.74046259551;6982;7037.261087585832;7070.36366726997;7091.326323999212;7140;3923.4899463070187;3994.6005399459823;4001.379786133154;4002.9792402937064;4003;4228.175505776406;4260.360945858084;4375.146846364992;4382.386465894777;4384.794181600086;914.8216097860853;934.1592566689898;944.3672739264572;950.2635457520339;952.4142652268805;640.8237734623016;664.9069130321759;670.7048898484651;672.6939242644585;687.2165731066588;10010.8498372523;10437.68686939393;11341.334786325207;11988.700282493008;12152.270863748203;654.5501713704073;661.5170925224613;662.68522451835;674.7672053141658;691.1429827014466;5379;5384.811531596375;5504.6421982571;5522.861928451741;5621.747021383966;108579;109368;110558;110883;111741.88258117477;75751;95615;96196;96665.51667241618;98179.50910245403;83222.58388708018;83454.74817877737;84212;84580.73128903071;86050.13949860547;10845.295055821662;10938.343699378063;11241.831372529583;11295;11494.540218391505;40171.79914100528;40203.59796402116;40510.594894051865;40553.78338649845;40918.38622420726;43391.78304108459;52036.479635204676;52116;52649.47350526599;52916;13942.721145576828;14059.297035147996;14256.215908124737;14343;14356.712865742418;12889.871101289242;14956.626084347978;15111.54665360041;15636.843631563539;15703.842961570239;14243.501477448239;14314.928425357806;14350.067245629001;14375.274967004027;14501.129932204101;13967.441302347792;14430.567082987527;14448.711025779636;14565.27173641335;14571.92714036423;13880.305984700926;14162.079464834753;14235.50175743844;14338.784918226444;14416.197975253093;9969.700908972742;10140.949295253477;10218;10342.793144136924;10547.630832920811;11089.445527723743;11102.722431939266;11185.32888026721;11295.039921606756;11303.7739245213 05:01:48 INFO - PID 2045 | __end_tp_report 05:01:48 INFO - PID 2045 | __start_cc_report 05:01:48 INFO - PID 2045 | _x_x_mozilla_cycle_collect,405 05:01:48 INFO - PID 2045 | __end_cc_report 05:01:48 INFO - PID 2045 | __startTimestamp1494072108810__endTimestamp 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | ------- Summary: start ------- 05:01:48 INFO - PID 2045 | Number of tests: 6 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | [#0] dojo.html Cycles:165 Average:6181.02 Median:6451.23 stddev:801.48 (12.4%) stddev-sans-first:776.70 05:01:48 INFO - PID 2045 | Values: 3539.9 3604.6 3611.9 3649.2 3663.3 4734.4 6150.4 6152.8 6192.4 6268.7 6189.4 6199.5 6247.3 6255.9 6272.6 4953.1 5256.7 5359.1 5418.4 5436.6 5046.3 5526.5 5616.1 5624.1 5657.5 5507.0 5522.1 5562.9 5563.9 5598.4 4029.8 5313.8 5524.9 5540.5 5551.7 5448.8 5467.4 5514.3 5515.8 5531.0 4617.9 6149.8 6177.6 6228.3 6234.7 6763.5 6773.3 6790.7 6797.5 6804.2 5744.7 6893.2 6897.4 6905.6 6905.8 6190.9 6220.4 6293.4 6367.8 6371.6 7019.4 7049.8 7054.2 7062.3 7070.4 5846.9 6440.5 6488.5 6507.2 6617.6 6641.9 6816.9 6851.9 6856.8 6857.7 6507.2 6800.4 6802.4 6802.4 6827.2 6640.3 6646.8 6687.5 6711.1 6811.3 5367.5 5377.9 5393.8 5404.3 5429.9 4696.4 4780.0 4788.1 4799.3 4847.9 5386.4 6242.6 6250.0 6254.8 6380.1 6837.8 6854.8 6929.5 6933.5 6934.8 5746.4 6164.0 6468.5 6487.7 6490.0 5906.1 5972.8 6012.5 6058.4 6085.6 7055.3 7070.5 7093.8 7101.1 7136.3 6913.6 6927.3 6941.0 6950.5 6960.6 7001.5 7056.2 7155.2 7168.5 7192.4 5583.9 6482.6 6542.5 6683.0 6763.2 6913.5 6917.1 6925.4 6928.0 6935.8 5043.3 6058.6 6385.3 6469.4 6655.5 6453.3 6507.1 6509.1 6510.8 6660.7 6344.8 6516.7 6532.6 6532.6 6536.9 6321.7 6451.2 6548.3 6587.4 6675.5 5471.2 6466.3 6515.6 6531.6 6536.5 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | [#1] ext.html Cycles:180 Average:40612.06 Median:18501.16 stddev:66767.79 (360.9%) stddev-sans-first:66887.66 05:01:48 INFO - PID 2045 | Values: 645.9 730.8 798.6 1106.2 1221.4 35460.3 36456.7 36476.0 36492.0 36493.8 36048.8 36121.5 36315.3 36336.3 36454.5 7640.7 7777.5 8257.8 8360.6 8404.0 8298.3 8363.3 8377.8 8385.8 8408.5 8363.8 8381.1 8408.5 8417.5 8433.9 8332.1 8358.2 8385.8 8394.2 8405.5 8267.1 8286.2 8326.4 8337.2 8346.6 36494.3 36652.1 36767.3 36790.0 36954.0 3530.3 3561.8 3563.2 3563.9 3568.9 30110.0 30364.9 30472.0 30489.7 30491.8 3036.8 3153.3 3155.0 3173.2 3180.0 196407.1 218770.0 272913.0 297394.0 299260.0 46818.1 46831.5 47212.5 47242.8 47247.8 40888.0 42875.0 46028.6 48344.5 49214.0 13311.8 13907.8 14049.1 14053.7 14061.9 11456.3 11546.4 11558.8 11566.5 11596.1 11080.8 13137.4 13255.5 13265.4 13284.2 5931.3 5943.1 6002.0 6006.2 6025.0 944.9 957.6 970.2 974.3 1009.2 19385.9 22207.6 23249.0 23361.5 23655.5 921.2 956.5 1025.6 1055.7 1095.2 9521.6 11627.4 11977.3 12028.2 12028.8 287949.0 287968.0 289226.0 289672.0 289961.0 53472.7 53489.7 53642.2 53739.5 53805.5 1487.5 1534.9 1540.0 1544.3 1557.7 19321.8 20331.8 23552.5 23801.0 23815.0 37561.0 48154.0 49019.0 49289.8 49404.3 209260.0 209751.0 210465.0 210751.0 211386.9 22691.7 28363.6 31227.8 31873.5 32433.7 33472.0 40814.2 43025.4 43103.0 43374.6 29908.1 30056.2 30236.6 30390.7 30575.5 29773.4 29819.4 30247.5 30380.7 30989.2 35409.5 36090.1 36516.8 36546.7 36580.8 28494.7 29921.6 30433.4 30629.7 30836.1 22059.8 23692.1 24035.3 24120.0 24205.0 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | [#2] jquery.html Cycles:180 Average:30369.96 Median:2312.95 stddev:70462.68 (3046.4%) stddev-sans-first:70624.49 05:01:48 INFO - PID 2045 | Values: 427.9 434.2 434.4 449.4 456.2 19368.7 21099.8 21546.0 21727.6 21846.1 18048.9 22654.1 23564.6 23584.0 23852.3 6145.4 6288.6 6331.1 6344.1 6365.9 5859.6 6128.8 6177.4 6181.4 6189.9 5978.4 6006.4 6087.1 6148.0 6175.9 6086.1 6157.6 6169.0 6178.5 6196.3 5388.1 5399.8 5422.9 5472.6 5486.4 27052.0 27291.5 27559.2 28046.7 28430.4 2236.4 2302.8 2310.2 2311.9 2314.0 17441.0 17585.9 17756.3 18218.0 18414.6 2136.8 2139.6 2175.5 2187.1 2190.4 128062.0 151258.2 153835.0 153943.0 154859.0 10396.8 11260.8 12243.1 12812.8 12831.6 16208.3 16349.9 16355.4 16369.4 16386.0 8888.0 9093.5 9105.4 9159.0 9260.2 7210.6 8647.5 8668.1 8799.6 8811.0 6249.8 7118.0 7948.8 8039.1 8074.2 2168.1 2168.2 2180.3 2188.2 2377.5 943.9 969.9 970.3 980.3 1018.9 19871.9 21182.8 21749.3 21825.2 21840.0 841.0 985.9 989.9 1015.5 1029.3 9779.7 11113.5 11200.1 11247.0 11257.3 355362.0 360618.0 423546.0 439933.0 440924.0 101816.5 103057.0 109438.0 110887.0 113043.9 1077.4 1124.8 1145.7 1156.6 1159.7 17202.7 18053.3 18169.2 18195.3 18396.6 39576.0 51831.0 51880.0 52349.6 52722.7 72377.6 72632.6 73258.6 73561.0 73972.9 8374.1 8887.1 8891.1 8894.6 9085.8 13330.8 13751.8 13785.7 14013.8 14061.4 11675.0 11725.3 12041.3 12116.6 12249.4 9391.8 11235.4 11295.4 11306.0 11315.3 11837.7 11948.2 12159.6 12224.4 12245.8 7410.8 8433.0 8616.6 8759.5 8950.2 11050.7 11275.5 11757.5 11884.4 12045.1 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | [#3] mootools.html Cycles:180 Average:6962.37 Median:4083.65 stddev:4363.21 (106.8%) stddev-sans-first:4353.67 05:01:48 INFO - PID 2045 | Values: 1161.5 1205.8 1377.4 1429.6 1433.3 9806.9 10650.9 11430.0 11525.3 11779.7 11425.9 11541.7 11583.9 11590.5 11596.5 2506.0 2547.7 2550.6 2551.3 2555.2 2152.9 2463.1 2502.7 2504.1 2514.1 2453.5 2479.8 2481.3 2508.6 2514.1 2452.6 2473.3 2500.7 2506.3 2509.4 2391.4 2495.5 2503.4 2509.2 2514.8 9431.5 11329.8 11461.0 11475.5 11498.1 6472.4 6637.8 6705.1 6712.0 6733.1 4044.3 4353.5 4375.9 4377.9 4439.9 1224.3 1244.5 1288.5 1291.9 1294.9 15664.8 16224.2 16288.0 16293.6 16308.4 5355.8 5480.0 5597.5 5608.3 5649.2 11485.1 11529.4 11580.5 11595.7 11595.8 6491.3 6509.2 6512.0 6516.9 6525.2 5876.5 6053.0 6136.0 6218.8 6254.8 4293.4 4349.7 4391.1 4393.7 4430.6 1243.0 1264.7 1268.5 1278.4 1288.0 758.7 776.2 784.6 786.5 801.6 6465.1 6711.1 6841.4 6855.0 6909.9 926.7 946.9 953.3 959.3 960.5 3515.6 3867.2 4123.0 4216.0 4218.6 14112.6 14453.0 14635.9 14706.4 14710.4 14067.1 14374.6 14587.0 14587.8 14617.7 6277.5 8450.4 8556.8 8565.8 8583.5 6064.2 6514.7 6702.7 6758.3 6927.9 12365.9 12922.8 13157.7 13198.6 13243.5 13036.8 13475.7 13538.0 13592.0 13737.7 7999.6 9163.6 9180.0 9381.6 9387.2 10720.9 10880.9 10902.9 10908.8 10933.9 7194.2 8925.6 8946.5 9028.2 9098.2 7805.6 8450.2 8683.6 8882.8 8946.5 7039.4 8007.4 8099.4 8123.9 8138.3 5977.5 5985.2 6072.9 6124.8 6154.5 5015.5 5976.5 6148.7 6175.8 6242.8 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | [#4] prototype.html Cycles:180 Average:5408.62 Median:6258.66 stddev:1760.26 (28.1%) stddev-sans-first:1724.29 05:01:48 INFO - PID 2045 | Values: 381.8 383.2 391.4 405.8 406.1 5374.3 6315.1 6387.7 6398.2 6454.5 6092.6 6226.4 6261.9 6266.2 6272.7 3780.2 4458.9 4557.7 4583.5 4593.9 4581.0 4585.4 4601.7 4606.6 4610.4 3776.4 4540.9 4553.6 4553.7 4594.8 3885.4 4387.9 4527.6 4570.8 4591.0 3199.6 3200.5 3846.6 3881.7 3896.8 5107.0 6197.0 6198.7 6222.8 6234.3 4422.3 4507.4 5371.2 5398.8 5440.6 6353.7 6436.3 6589.2 6651.3 6703.3 4245.7 4375.8 4380.9 4386.0 4390.6 5988.6 6937.4 7015.1 7091.8 7164.2 3898.6 3941.3 4283.8 4348.6 4431.2 4523.6 4538.5 4546.6 4555.8 4556.6 5330.5 5350.7 5379.8 5393.0 5482.5 6732.4 6757.0 6777.7 6780.9 6819.8 1527.5 1546.3 1552.2 1553.9 1558.9 868.0 928.6 969.2 988.1 989.2 5616.0 5713.4 6434.1 6489.8 6528.6 6930.9 7027.5 7038.6 7054.5 7057.7 6355.7 6402.4 6406.3 6423.5 6455.4 3223.8 3301.8 3677.3 3689.2 3701.2 6249.9 6706.9 7072.0 7195.4 7262.6 6785.4 6910.4 7178.1 7191.3 7222.2 7076.2 7214.2 7262.5 7264.2 7336.5 6101.6 6778.9 6844.3 7048.3 7097.4 6863.8 6896.6 6916.8 6917.2 6954.8 6906.6 7094.8 7131.5 7260.9 7283.6 4415.9 4428.8 4440.8 4457.8 4468.4 6143.8 6295.0 6869.9 6930.2 6934.8 5500.4 6198.9 6706.6 6760.2 6766.9 5754.4 6598.7 6705.6 6718.5 6765.6 5019.5 6245.3 6255.4 6431.4 6640.4 6388.7 6430.1 6782.7 6814.2 6846.8 5930.4 6653.6 6657.9 6665.4 6670.5 05:01:48 INFO - PID 2045 | 05:01:48 INFO - PID 2045 | [#5] yui.html Cycles:180 Average:21066.82 Median:17001.69 stddev:30320.11 (178.3%) stddev-sans-first:30366.53 05:01:48 INFO - PID 2045 | Values: 679.9 814.6 827.5 857.9 867.8 15826.0 16404.8 16426.8 18521.7 19149.0 15203.6 15710.4 17836.3 18173.3 18372.5 979.2 987.4 989.8 999.6 1000.9 1011.6 1012.0 1013.7 1015.2 1015.4 1046.6 1063.4 1068.4 1068.8 1071.2 1038.3 1046.3 1060.6 1060.7 1062.8 762.9 776.5 791.6 792.6 795.8 13683.5 17193.1 17295.0 17301.8 17313.8 12649.9 15469.0 16094.8 16583.8 16810.2 13474.5 14236.9 14279.1 14368.0 14370.2 3474.4 3489.3 3510.1 3561.9 3572.6 100936.0 109328.4 118542.4 119648.0 121270.0 11482.5 12017.8 13517.9 13669.1 13720.2 21083.7 25222.5 25564.4 25944.0 25953.7 6982.0 7037.3 7070.4 7091.3 7140.0 3923.5 3994.6 4001.4 4003.0 4003.0 4228.2 4260.4 4375.1 4382.4 4384.8 914.8 934.2 944.4 950.3 952.4 640.8 664.9 670.7 672.7 687.2 10010.8 10437.7 11341.3 11988.7 12152.3 654.6 661.5 662.7 674.8 691.1 5379.0 5384.8 5504.6 5522.9 5621.7 108579.0 109368.0 110558.0 110883.0 111741.9 75751.0 95615.0 96196.0 96665.5 98179.5 83222.6 83454.7 84212.0 84580.7 86050.1 10845.3 10938.3 11241.8 11295.0 11494.5 40171.8 40203.6 40510.6 40553.8 40918.4 43391.8 52036.5 52116.0 52649.5 52916.0 13942.7 14059.3 14256.2 14343.0 14356.7 12889.9 14956.6 15111.5 15636.8 15703.8 14243.5 14314.9 14350.1 14375.3 14501.1 13967.4 14430.6 14448.7 14565.3 14571.9 13880.3 14162.1 14235.5 14338.8 14416.2 9969.7 10140.9 10218.0 10342.8 10547.6 11089.4 11102.7 11185.3 11295.0 11303.8 05:01:48 INFO - PID 2045 | -------- Summary: end -------- 05:01:48 INFO - PID 2045 | 05:01:49 INFO - TEST-INFO | 2045: exit 0 05:01:49 INFO - TEST-OK | dromaeo_css | took 1115671ms 05:01:49 INFO - TEST-START | kraken 05:01:49 INFO - operating with platform_type : mac_ 05:01:49 INFO - Initialising browser for kraken test... 05:01:49 INFO - TEST-INFO | started process 2053 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmp1KafDQ/profile http://localhost:53090/getInfo.html) 05:01:52 INFO - PID 2053 | 2017-05-06 05:01:52.304 plugin-container[2054:23543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 05:01:52 INFO - PID 2053 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:52 INFO - PID 2053 | 2017-05-06 05:01:52.305 plugin-container[2054:23543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 05:01:52 INFO - PID 2053 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:52 INFO - PID 2053 | 2017-05-06 05:01:52.305 plugin-container[2054:23543] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:52 INFO - PID 2053 | __metrics Screen width/height:1600/1200 05:01:52 INFO - PID 2053 | colorDepth:24 05:01:52 INFO - PID 2053 | Browser inner width/height: 1024/691 05:01:52 INFO - PID 2053 | __metrics 05:01:52 INFO - PID 2053 | 2017-05-06 05:01:52.772 plugin-container[2055:23586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 05:01:52 INFO - PID 2053 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:52 INFO - PID 2053 | 2017-05-06 05:01:52.773 plugin-container[2055:23586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 05:01:52 INFO - PID 2053 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:52 INFO - PID 2053 | 2017-05-06 05:01:52.773 plugin-container[2055:23586] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:53 INFO - TEST-INFO | 2053: exit 0 05:01:53 INFO - Browser initialized. 05:01:53 INFO - Running cycle 1/1 for kraken test... 05:01:53 INFO - TEST-INFO | started process 2056 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/T/tmp1KafDQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:56 INFO - PID 2056 | 2017-05-06 05:01:56.055 plugin-container[2057:23694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 05:01:56 INFO - PID 2056 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:56 INFO - PID 2056 | 2017-05-06 05:01:56.056 plugin-container[2057:23694] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.CFPasteboardClient' 05:01:56 INFO - PID 2056 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:56 INFO - PID 2056 | 2017-05-06 05:01:56.056 plugin-container[2057:23694] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:01:56 INFO - PID 2056 | 2017-05-06 05:01:56.567 plugin-container[2058:23735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x922f, name = 'com.apple.tsm.portname' 05:01:56 INFO - PID 2056 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:56 INFO - PID 2056 | 2017-05-06 05:01:56.568 plugin-container[2058:23735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 05:01:56 INFO - PID 2056 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:01:56 INFO - PID 2056 | 2017-05-06 05:01:56.568 plugin-container[2058:23735] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:02:05 INFO - PID 2056 | RSS: Main: 233865216 05:02:05 INFO - PID 2056 | 05:02:08 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53090/tests/kraken/driver.html?testName=audio-beat-detection) 05:02:09 INFO - PID 2056 | RSS: Main: 246669312 05:02:09 INFO - PID 2056 | 05:02:12 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53090/tests/kraken/driver.html?testName=audio-dft) 05:02:12 INFO - PID 2056 | RSS: Main: 254988288 05:02:12 INFO - PID 2056 | 05:02:16 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53090/tests/kraken/driver.html?testName=audio-fft) 05:02:17 INFO - PID 2056 | RSS: Main: 257560576 05:02:17 INFO - PID 2056 | 05:02:19 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53090/tests/kraken/driver.html?testName=audio-oscillator) 05:02:19 INFO - PID 2056 | RSS: Main: 259624960 05:02:19 INFO - PID 2056 | 05:02:21 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53090/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:02:22 INFO - PID 2056 | RSS: Main: 259993600 05:02:22 INFO - PID 2056 | 05:02:25 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53090/tests/kraken/driver.html?testName=imaging-darkroom) 05:02:25 INFO - PID 2056 | RSS: Main: 265646080 05:02:25 INFO - PID 2056 | 05:02:28 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53090/tests/kraken/driver.html?testName=imaging-desaturate) 05:02:29 INFO - PID 2056 | RSS: Main: 267452416 05:02:29 INFO - PID 2056 | 05:02:32 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53090/tests/kraken/driver.html?testName=json-parse-financial) 05:02:33 INFO - PID 2056 | RSS: Main: 267296768 05:02:33 INFO - PID 2056 | 05:02:34 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53090/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:02:35 INFO - PID 2056 | RSS: Main: 266760192 05:02:35 INFO - PID 2056 | 05:02:37 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:02:37 INFO - PID 2056 | RSS: Main: 266719232 05:02:37 INFO - PID 2056 | 05:02:39 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:02:40 INFO - PID 2056 | RSS: Main: 266424320 05:02:40 INFO - PID 2056 | 05:02:43 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:02:44 INFO - PID 2056 | RSS: Main: 266661888 05:02:44 INFO - PID 2056 | 05:02:47 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:02:48 INFO - PID 2056 | RSS: Main: 266842112 05:02:48 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | Cycle 1(1): loaded http://localhost:53090/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53090/tests/kraken/driver.html?testName=ai-astar) 05:02:49 INFO - PID 2056 | RSS: Main: 268718080 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | __start_tp_report 05:02:49 INFO - PID 2056 | _x_x_mozilla_page_load 05:02:49 INFO - PID 2056 | _x_x_mozilla_page_load_details 05:02:49 INFO - PID 2056 | |i|pagename|runs| 05:02:49 INFO - PID 2056 | |0;ai-astar;114;145;168;170;113;114;117;113;113;114 05:02:49 INFO - PID 2056 | |1;audio-beat-detection;147;112;126;145;114;99;144;143;162;107 05:02:49 INFO - PID 2056 | |2;audio-dft;200;229;242;221;231;277;277;201;198;198 05:02:49 INFO - PID 2056 | |3;audio-fft;85;53;65;112;83;84;71;86;90;49 05:02:49 INFO - PID 2056 | |4;audio-oscillator;85;73;103;68;68;73;88;70;69;91 05:02:49 INFO - PID 2056 | |5;imaging-gaussian-blur;89;90;91;89;89;89;90;97;90;88 05:02:49 INFO - PID 2056 | |6;imaging-darkroom;97;98;96;149;96;97;97;96;97;98 05:02:49 INFO - PID 2056 | |7;imaging-desaturate;93;94;89;78;146;145;90;92;119;149 05:02:49 INFO - PID 2056 | |8;json-parse-financial;50;51;51;53;51;51;52;51;51;49 05:02:49 INFO - PID 2056 | |9;json-stringify-tinderbox;59;57;63;80;67;63;57;59;49;77 05:02:49 INFO - PID 2056 | |10;stanford-crypto-aes;82;82;82;81;85;78;97;89;84;82 05:02:49 INFO - PID 2056 | |11;stanford-crypto-ccm;135;146;140;140;135;138;163;155;204;178 05:02:49 INFO - PID 2056 | |12;stanford-crypto-pbkdf2;185;188;189;187;188;189;187;184;187;241 05:02:49 INFO - PID 2056 | |13;stanford-crypto-sha256-iterative;70;66;70;67;71;66;70;68;70;82 05:02:49 INFO - PID 2056 | __end_tp_report 05:02:49 INFO - PID 2056 | __start_cc_report 05:02:49 INFO - PID 2056 | _x_x_mozilla_cycle_collect,984 05:02:49 INFO - PID 2056 | __end_cc_report 05:02:49 INFO - PID 2056 | __startTimestamp1494072169964__endTimestamp 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | ------- Summary: start ------- 05:02:49 INFO - PID 2056 | Number of tests: 14 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#0] ai-astar Cycles:10 Average:128.10 Median:115.50 stddev:23.66 (20.5%) stddev-sans-first:24.54 05:02:49 INFO - PID 2056 | Values: 114.0 145.0 168.0 170.0 113.0 114.0 117.0 113.0 113.0 114.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:144.50 stddev:21.05 (14.6%) stddev-sans-first:21.40 05:02:49 INFO - PID 2056 | Values: 147.0 112.0 126.0 145.0 114.0 99.0 144.0 143.0 162.0 107.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#2] audio-dft Cycles:10 Average:227.40 Median:230.00 stddev:30.49 (13.3%) stddev-sans-first:30.68 05:02:49 INFO - PID 2056 | Values: 200.0 229.0 242.0 221.0 231.0 277.0 277.0 201.0 198.0 198.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#3] audio-fft Cycles:10 Average:77.80 Median:83.50 stddev:18.73 (22.4%) stddev-sans-first:19.69 05:02:49 INFO - PID 2056 | Values: 85.0 53.0 65.0 112.0 83.0 84.0 71.0 86.0 90.0 49.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:73.00 stddev:12.16 (16.7%) stddev-sans-first:12.69 05:02:49 INFO - PID 2056 | Values: 85.0 73.0 103.0 68.0 68.0 73.0 88.0 70.0 69.0 91.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:90.00 stddev:2.53 (2.8%) stddev-sans-first:2.65 05:02:49 INFO - PID 2056 | Values: 89.0 90.0 91.0 89.0 89.0 89.0 90.0 97.0 90.0 88.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:97.00 stddev:16.50 (17.0%) stddev-sans-first:17.39 05:02:49 INFO - PID 2056 | Values: 97.0 98.0 96.0 149.0 96.0 97.0 97.0 96.0 97.0 98.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#7] imaging-desaturate Cycles:10 Average:109.50 Median:89.50 stddev:27.60 (30.8%) stddev-sans-first:28.62 05:02:49 INFO - PID 2056 | Values: 93.0 94.0 89.0 78.0 146.0 145.0 90.0 92.0 119.0 149.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.05 (2.1%) stddev-sans-first:1.05 05:02:49 INFO - PID 2056 | Values: 50.0 51.0 51.0 53.0 51.0 51.0 52.0 51.0 51.0 49.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:63.00 stddev:9.43 (15.0%) stddev-sans-first:9.89 05:02:49 INFO - PID 2056 | Values: 59.0 57.0 63.0 80.0 67.0 63.0 57.0 59.0 49.0 77.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:83.00 stddev:5.33 (6.4%) stddev-sans-first:5.59 05:02:49 INFO - PID 2056 | Values: 82.0 82.0 82.0 81.0 85.0 78.0 97.0 89.0 84.0 82.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:150.50 stddev:22.58 (15.0%) stddev-sans-first:22.95 05:02:49 INFO - PID 2056 | Values: 135.0 146.0 140.0 140.0 135.0 138.0 163.0 155.0 204.0 178.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.50 Median:188.00 stddev:17.12 (9.1%) stddev-sans-first:17.94 05:02:49 INFO - PID 2056 | Values: 185.0 188.0 189.0 187.0 188.0 189.0 187.0 184.0 187.0 241.0 05:02:49 INFO - PID 2056 | 05:02:49 INFO - PID 2056 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:70.00 stddev:4.59 (6.6%) stddev-sans-first:4.87 05:02:49 INFO - PID 2056 | Values: 70.0 66.0 70.0 67.0 71.0 66.0 70.0 68.0 70.0 82.0 05:02:49 INFO - PID 2056 | -------- Summary: end -------- 05:02:49 INFO - PID 2056 | 05:02:50 INFO - TEST-INFO | 2056: exit 0 05:02:51 INFO - TEST-OK | kraken | took 61490ms 05:02:51 INFO - SUITE-END | took 1177s 05:02:51 INFO - Completed test suite (00:19:37) 05:02:51 INFO - javascript benchmark 05:02:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7757.911654633212, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3539.946900796488, 3604.6395360463957, 3611.927761444773, 3649.1606930405997, 3663.3222853772054, 4734.43186817582, 6150.384961503847, 6152.8461788455315, 6192.380761923805, 6268.68656567171, 6189.412005859447, 6199.535034872391, 6247.312795592493, 6255.937440625592, 6272.592281501699, 4953.133201689708, 5256.737163141846, 5359.062164121283, 5418.4039755626845, 5436.646617969829, 5046.293518907356, 5526.530244929186, 5616.101423772197, 5624.100143976964, 5657.490825825675, 5507.008738427122, 5522.116461366182, 5562.916556251659, 5563.944360556424, 5598.384177740445, 4029.8388064477385, 5313.830957189412, 5524.861878453031, 5540.50135487804, 5551.694656793875, 5448.828501872077, 5467.371252305996, 5514.310711161105, 5515.834524964217, 5530.972345138289, 4617.907641847144, 6149.800788846177, 6177.62934223948, 6228.346023667495, 6234.688265586702, 6763.492738044666, 6773.288804675487, 6790.728370865159, 6797.456203503708, 6804.183497980274, 5744.712764361807, 6893.241743408221, 6897.379235868746, 6905.551139175937, 6905.827354316132, 6190.907136392958, 6220.409061139185, 6293.370662933761, 6367.777127800551, 6371.585846919982, 7019.403350715246, 7049.753258636026, 7054.2240353561065, 7062.293770622897, 7070.36366726997, 5846.912296315559, 6440.4847612190915, 6488.545801793829, 6507.186601674791, 6617.636030018299, 6641.933580664228, 6816.931830681729, 6851.931480685229, 6856.828579285558, 6857.657117144123, 6507.219133703986, 6800.387965083166, 6802.3537763912345, 6802.4217941474535, 6827.180738311435, 6640.335966403418, 6646.767363142267, 6687.498437617198, 6711.060451536789, 6811.318868113149, 5367.463253674601, 5377.892442151135, 5393.7572809223675, 5404.32445944257, 5429.945700542944, 4696.38946936896, 4779.996200797802, 4788.138135135709, 4799.280107983761, 4847.854564363075, 5386.407495175527, 6242.5942313750365, 6249.968750156311, 6254.781082662085, 6380.0748891410485, 6837.829054273683, 6854.760083397157, 6929.549579277331, 6933.5493192942295, 6934.826629334206, 5746.4253574643035, 6163.96918015407, 6468.482521398277, 6487.6756162191705, 6490.02649602554, 5906.084556893688, 5972.761089556456, 6012.549058820606, 6058.4244496772735, 6085.6348619083, 7055.294470553006, 7070.505064645426, 7093.751718689821, 7101.147862256562, 7136.3220494052985, 6913.5506192097355, 6927.272636373108, 6940.9652951734915, 6950.54821436605, 6960.582365058112, 7001.4748893834185, 7056.15326160864, 7155.212926577969, 7168.462365322726, 7192.352688258082, 5583.888322233452, 6482.57863238888, 6542.54202205841, 6682.966585167043, 6763.188417389944, 6913.481488888253, 6917.13535808024, 6925.376716095576, 6928.0, 6935.757248496278, 5043.268726034631, 6058.57589968698, 6385.29761726219, 6469.417752402306, 6655.467562595078, 6453.322401148, 6507.089007539043, 6509.088727578048, 6510.7721229756735, 6660.700268487833, 6344.777932772331, 6516.73933042692, 6532.608043517214, 6532.608043517404, 6536.869262614816, 6321.715522801577, 6451.225852897683, 6548.279689234085, 6587.4071333580205, 6675.465962723068, 5471.23402723627, 6466.321036291121, 6515.641639709672, 6531.608103513804, 6536.509761767981], "value": 6128.28250414466, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.883740926633, 730.8428687832117, 798.6086817459443, 1106.2422240765072, 1221.370993938122, 35460.29079418413, 36456.724014659456, 36476.0, 36492.0, 36493.81753091231, 36048.81975590125, 36121.45817812735, 36315.27369452616, 36336.27327453449, 36454.453183202204, 7640.694372225118, 7777.533347999138, 8257.752267431986, 8360.623771930248, 8403.957980210122, 8298.25315721585, 8363.289120424772, 8377.790555236106, 8385.790355241106, 8408.453450525696, 8363.83272334553, 8381.119982401851, 8408.453450525696, 8417.494950302971, 8433.873491897626, 8332.083470818205, 8358.164183581654, 8385.832283354359, 8394.20255075773, 8405.453645513053, 8267.090620031791, 8286.171382861665, 8326.375521835887, 8337.207965243353, 8346.582670866432, 36494.27011459783, 36652.083697907765, 36767.264654706756, 36790.0, 36954.0, 3530.3115892400992, 3561.8041007744564, 3563.1804684922354, 3563.8574457021687, 3568.91077723059, 30110.0, 30364.937227197162, 30472.0, 30489.69510304913, 30491.847540762377, 3036.832974186419, 3153.290509635314, 3154.952675709866, 3173.2066983254185, 3179.9682003180133, 196407.07185856346, 218770.0, 272913.0, 297394.0, 299260.0, 46818.063638727035, 46831.53168468272, 47212.5278747215, 47242.76378618085, 47247.76376118166, 40888.0, 42875.0, 46028.619141425814, 48344.51655483471, 49214.0, 13311.800322995163, 13907.791383129059, 14049.086809357359, 14053.71892562143, 14061.859381406259, 11456.312621242585, 11546.422678866023, 11558.768824623461, 11566.537338506534, 11596.072314214842, 11080.836512166268, 13137.408816603276, 13255.536056237985, 13265.403056862271, 13284.202947823162, 5931.317898441648, 5943.108533719977, 6002.0, 6006.249218847644, 6024.969875150597, 944.8771659684197, 957.6169532187181, 970.2141265574766, 974.2936371131015, 1009.1573536097294, 19385.903070484557, 22207.55584888293, 23248.95379707917, 23361.532769344518, 23655.526889462115, 921.235374639048, 956.4787190980932, 1025.600015993763, 1055.7096798380508, 1095.1840878545534, 9521.619135234652, 11627.418629068512, 11977.323174755542, 12028.21816581937, 12028.819567706492, 287949.0, 287968.0, 289226.0, 289672.0, 289961.0, 53472.73263633657, 53489.73255133777, 53642.19536706952, 53739.462605374225, 53805.46194538083, 1487.5462983790048, 1534.884883633732, 1539.992300038515, 1544.3282172255106, 1557.688462307543, 19321.806781932002, 20331.796682033582, 23552.52894942126, 23801.0, 23815.0, 37561.0, 48154.0, 49019.0, 49289.75355123202, 49404.25893611527, 209260.0, 209751.0, 210465.0, 210751.0, 211386.9430652837, 22691.659625105305, 28363.57454638141, 31227.84386078055, 31873.521897171096, 32433.675663243066, 33472.0, 40814.1837163261, 43025.3546196801, 43103.0, 43374.566254337056, 29908.102756917327, 30056.248593784454, 30236.593138351098, 30390.69609303967, 30575.541366879068, 29773.404531908807, 29819.403611928075, 30247.546286805275, 30380.696193037787, 30989.225269368446, 35409.46885796663, 36090.09774755547, 36516.81741591275, 36546.7208647696, 36580.81709591435, 28494.715052849206, 29921.551176732803, 30433.39133217279, 30629.693703063575, 30836.074917752503, 22059.779402206415, 23692.05231790778, 24035.27894163178, 24120.0, 24205.0], "value": 16852.5096290939, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.9336702811065, 434.1729006243106, 434.35281430668294, 449.438202247191, 456.1834316573334, 19368.709469357953, 21099.789002109974, 21546.0, 21727.565448691017, 21846.126154953803, 18048.909755451205, 22654.1487947049, 23564.64653030206, 23584.0, 23852.284431467084, 6145.385461453852, 6288.591241569284, 6331.050342448623, 6344.080108384282, 6365.936340636581, 5859.560532960045, 6128.8467788305425, 6177.413145751147, 6181.381861813805, 6189.938100619003, 5978.40215978403, 6006.399360064002, 6087.147799308101, 6147.969260153693, 6175.907361389561, 6086.147939288503, 6157.568970172089, 6169.043798211262, 6178.474829639441, 6196.349383314755, 5388.0570900092325, 5399.784008639689, 5422.945770542323, 5472.562195024429, 5486.366544058834, 27052.0, 27291.45417091667, 27559.173224803086, 28046.719532804615, 28430.431391372058, 2236.3626366485473, 2302.769723027684, 2310.1567927706433, 2311.9422014449606, 2313.953720925589, 17440.953542787473, 17585.944843309313, 17756.289748410047, 18218.0, 18414.631707365777, 2136.807687308134, 2139.6148693235214, 2175.510510135223, 2187.0923566719794, 2190.4304880731124, 128062.0, 151258.24370878184, 153835.0, 153943.0, 154859.0, 10396.764695590484, 11260.774784504263, 12243.08176886737, 12812.80780788289, 12831.615051548468, 16208.270627821776, 16349.918250408671, 16355.427560035341, 16369.427070052729, 16386.0, 8888.0, 9093.54532273397, 9105.408148470327, 9159.0, 9260.166585007382, 7210.639468026683, 8647.524386158822, 8668.0898505656, 8799.604017819214, 8810.955945220232, 6249.75004999002, 7117.964410177916, 7948.801279967932, 8039.11080895989, 8074.152214017444, 2168.1435832845937, 2168.241115609524, 2180.269609680775, 2188.245055455865, 2377.5007248478014, 943.8584212368199, 969.9321047526746, 970.3207754571687, 980.2501086668705, 1018.8624535687735, 19871.900640496995, 21182.78817211839, 21749.34751957444, 21825.23611673584, 21840.017199226073, 840.9537475438788, 985.935914165577, 989.8663680403198, 1015.5125539740966, 1029.3206483720708, 9779.657711980046, 11113.499892504857, 11200.103991680646, 11246.987771100641, 11257.268277561932, 355362.0, 360618.0, 423546.0, 439933.0, 440924.0, 101816.49091754494, 103057.0, 109438.0, 110887.0, 113043.86956130498, 1077.3966578715945, 1124.791913496006, 1145.7479354541988, 1156.5951916829042, 1159.7390587118, 17202.741958870378, 18053.279507574076, 18169.18238679289, 18195.272189112286, 18396.632067358845, 39576.0, 51831.0, 51880.0, 52349.64426600736, 52722.736386317825, 72377.63811180911, 72632.63683681548, 73258.63370683113, 73561.0, 73972.94514042315, 8374.120717324593, 8887.066857980077, 8891.066438024172, 8894.555272236492, 9085.77285567866, 13330.800037999245, 13751.79372309396, 13785.655358616115, 14013.789793152908, 14061.36723847409, 11675.007624351685, 11725.296482211095, 12041.337726424781, 12116.636500904988, 12249.387530623611, 9391.765205869908, 11235.382053986783, 11295.3787541686, 11306.038986685895, 11315.284331760482, 11837.704057398634, 11948.163628545919, 12159.635210943687, 12224.38878056076, 12245.816312755138, 7410.814729631802, 8433.0, 8616.612252448529, 8759.474431534129, 8950.194482496607, 11050.673919129435, 11275.492602833056, 11757.529698811952, 11884.405779710807, 12045.096617753528], "value": 10190.652844240512, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1161.4889448642602, 1205.7709035283297, 1377.387062757073, 1429.6354429620446, 1433.3406632948843, 9806.901930980688, 10650.946745266283, 11429.99999999998, 11525.25085869419, 11779.70550736232, 11425.942870285659, 11541.65375038748, 11583.942080289607, 11590.478428470718, 11596.478158482867, 2505.9624105638477, 2547.700672656949, 2550.591905295153, 2551.3494059014924, 2555.169569889784, 2152.8923553822324, 2463.088657196831, 2502.724700282967, 2504.1485894795765, 2514.0823599386185, 2453.472503411766, 2479.789217916479, 2481.3176376496517, 2508.5610018246825, 2514.0823599386185, 2452.6443665668467, 2473.332200305925, 2500.7499250074848, 2506.2982364937848, 2509.3814489654255, 2391.426057746128, 2495.4510007798253, 2503.4492411669403, 2509.1719732488236, 2514.8491090534626, 9431.528423578862, 11329.773404531863, 11461.0, 11475.540978360856, 11498.080153587776, 6472.352764723537, 6637.767678131242, 6705.061291419205, 6712.0, 6733.091032710619, 4044.292248856438, 4353.521112677603, 4375.934360984587, 4377.868663940087, 4439.933400998988, 1224.3205021213223, 1244.4710997825887, 1288.5103660608909, 1291.8901893339078, 1294.8899343555809, 15664.765028524582, 16224.188790560425, 16288.0, 16293.59266018347, 16308.429204977887, 5355.785768569213, 5479.972600137054, 5597.496225339658, 5608.327000759935, 5649.152627105967, 11485.138614603939, 11529.365884876412, 11580.536778528765, 11595.652130436101, 11595.826062609067, 6491.253505846888, 6509.1863517060365, 6512.023196520559, 6516.869662606816, 6525.249596296487, 5876.500497457679, 6053.0, 6136.0, 6218.751249950042, 6254.843628909222, 4293.355996600535, 4349.652027837766, 4391.099824535962, 4393.6704747144095, 4430.645548356124, 1242.9813552796716, 1264.6648638110908, 1268.4989429175423, 1278.3799857069314, 1288.0, 758.707897459471, 776.2392855002016, 784.6351446577369, 786.4573444323513, 801.5711594297023, 6465.062565927914, 6711.060451536789, 6841.350071743176, 6855.0, 6909.861802763917, 926.6756635177634, 946.8674385586023, 953.3421938862301, 959.2853324273461, 960.4573416019927, 3515.5781306243416, 3867.2265546890735, 4123.0, 4216.0, 4218.578142185818, 14112.647183820487, 14453.0, 14635.853641463662, 14706.41174353014, 14710.411583536752, 14067.085639433404, 14374.568762937128, 14587.0, 14587.781183282259, 14617.707645847024, 6277.497800176066, 8450.408471407065, 8556.786080348042, 8565.828683426296, 8583.527905965107, 6064.211652485325, 6514.73941042353, 6702.698378572878, 6758.256591774999, 6927.930720692729, 12365.876341236473, 12922.806157907828, 13157.736845262849, 13198.60404187876, 13243.536476223286, 13036.804447933479, 13475.663108421975, 13538.0, 13592.048556600943, 13737.725245495234, 7999.600069987612, 9163.587638556148, 9180.036096209802, 9381.624735010522, 9387.249020078514, 10720.892791072301, 10880.891191088305, 10902.890971090188, 10908.836367454653, 10933.945330273298, 7194.244604316681, 8925.553722313729, 8946.507942062988, 9028.18746312835, 9098.226650734761, 7805.555972145171, 8450.239478446723, 8683.565821708762, 8882.777930551529, 8946.507942063248, 7039.401650859734, 8007.43947923663, 8099.392545558989, 8123.918760812317, 8138.3082437993435, 5977.462028417463, 5985.221921150222, 6072.90890636632, 6124.846878828065, 6154.476869466145, 5015.498450154955, 5976.491998180203, 6148.692565371803, 6175.814725558241, 6242.812715618538], "value": 5299.011091342986, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.84535263218373, 383.2277959910779, 391.43046866808794, 405.8011574328576, 406.14506463893514, 5374.301340825697, 6315.11588377627, 6387.658591695744, 6398.232212134544, 6454.451371633417, 6092.634441933487, 6226.43962043416, 6261.906071408933, 6266.216722909637, 6272.717727702253, 3780.2439512097553, 4458.948883513588, 4557.703749256304, 4583.541645835423, 4593.885152871188, 4581.037982023779, 4585.426821647294, 4601.7469039203, 4606.562376574237, 4610.423697037871, 3776.3761582519505, 4540.864783804049, 4553.5901768840795, 4553.68124231304, 4594.816207351702, 3885.445821671326, 4387.890302742425, 4527.592516673515, 4570.817167313304, 4590.967032417712, 3199.5840540729787, 3200.543752592565, 3846.6345697158727, 3881.6894648428056, 3896.8246428910484, 5106.974465127651, 6197.0, 6198.659073750942, 6222.844428889269, 6234.314225435199, 4422.292433210703, 4507.414036175309, 5371.248025276464, 5398.757055932492, 5440.646357986719, 6353.744420730621, 6436.259830119549, 6589.209294884645, 6651.268360480343, 6703.296153903819, 4245.74975263108, 4375.78121093944, 4380.912381752347, 4385.978070109661, 4390.560943905616, 5988.640681559077, 6937.375636192767, 7015.123109611299, 7091.787246382617, 7164.176119746244, 3898.6101389860787, 3941.265843029058, 4283.764392958355, 4348.630181492883, 4431.180231657154, 4523.615492683159, 4538.523455037173, 4546.636269098464, 4555.790441635804, 4556.56468212512, 5330.466953304716, 5350.732463376816, 5379.757910894019, 5393.0, 5482.506574408322, 6732.427743641845, 6757.0, 6777.72889084441, 6780.9150535915005, 6819.795406137824, 1527.4653871145233, 1546.2577962578025, 1552.1773460066183, 1553.8834587405763, 1558.932131489933, 867.9566021698889, 928.5635751196927, 969.2246203037541, 988.0761488008735, 989.2432289298692, 5615.971920140455, 5713.4000929902595, 6434.131392261985, 6489.772857949949, 6528.596351784389, 6930.925706515498, 7027.4729395294535, 7038.6480675965995, 7054.5061845671335, 7057.717691292393, 6355.650439225795, 6402.36739631391, 6406.327335629785, 6423.518236132309, 6455.419012288917, 3223.7582181336493, 3301.7783420134706, 3677.2645470905927, 3689.169936764207, 3701.1672373715696, 6249.875002499924, 6706.899396509057, 7071.974563688339, 7195.352418282379, 7262.564246145248, 6785.355391237825, 6910.44716422685, 7178.138623365229, 7191.280871912872, 7222.241664625244, 7076.150861896503, 7214.170370407365, 7262.491625586158, 7264.237255088246, 7336.486445948733, 6101.633901965896, 6778.8644227116165, 6844.349786770249, 7048.286409732393, 7097.432205423657, 6863.794086177422, 6896.586204827727, 6916.757913473004, 6917.239103698488, 6954.79135625932, 6906.585604863724, 7094.787156385317, 7131.536450130725, 7260.891086633599, 7283.629822106694, 4415.933760993524, 4428.778561071869, 4440.777961101868, 4457.777111144365, 4468.419105516262, 6143.784967526115, 6294.968525157345, 6869.931300687129, 6930.237673855972, 6934.821080416241, 5500.394956554695, 6198.907016394848, 6706.5640733352175, 6760.1549806274215, 6766.898496522457, 5754.395788442153, 6598.670066496752, 6705.597664140168, 6718.496112791462, 6765.594064356154, 5019.498050194951, 6245.344238854855, 6255.40573645503, 6431.389018043278, 6640.443429279666, 6388.744450221939, 6430.135260774323, 6782.660866956731, 6814.182298124257, 6846.7945961621235, 5930.436608522184, 6653.600783952978, 6657.866842663217, 6665.400113989765, 6670.499712521483], "value": 4847.430030019465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.8572299817038, 814.5723495165034, 827.5283088639478, 857.9485230886147, 867.796067924038, 15826.0, 16404.75392869108, 16426.83573164268, 18521.722174167397, 19149.0, 15203.619909502268, 15710.371585136609, 17836.286548538075, 18173.273069077222, 18372.540686482844, 979.1530326267798, 987.4075554667214, 989.8465737810651, 999.6201443451479, 1000.8899021107673, 1011.6408674920408, 1012.0233974214881, 1013.6502906497246, 1015.1624909449697, 1015.4161357219591, 1046.6336782126234, 1063.377923914511, 1068.433730123036, 1068.754186537097, 1071.234067641636, 1038.2887721910515, 1046.3146638951498, 1060.6234786650743, 1060.7401186709228, 1062.7715041266126, 762.9465937384385, 776.5301992294637, 791.6398038892348, 792.5522080024767, 795.7732046366758, 13683.452661893612, 17193.140342982802, 17295.048772317517, 17301.826981730148, 17313.826861731348, 12649.873501264961, 15468.994515356466, 16094.839051609451, 16583.751243731353, 16810.24353904065, 13474.461021559224, 14236.857631423656, 14279.143251404948, 14368.0, 14370.20963846988, 3474.3919814032442, 3489.284696637183, 3510.087377281925, 3561.94657080144, 3572.6427357264324, 100936.0, 109328.36007459974, 118542.40728796387, 119648.0, 121270.0, 11482.48328825205, 12017.759644807054, 13517.932410337886, 13669.111507751964, 13720.245386503839, 21083.68374474384, 25222.495550088894, 25564.360890977503, 25944.0, 25953.74046259551, 6982.0, 7037.261087585832, 7070.36366726997, 7091.326323999212, 7140.0, 3923.4899463070187, 3994.6005399459823, 4001.379786133154, 4002.9792402937064, 4003.0, 4228.175505776406, 4260.360945858084, 4375.146846364992, 4382.386465894777, 4384.794181600086, 914.8216097860853, 934.1592566689898, 944.3672739264572, 950.2635457520339, 952.4142652268805, 640.8237734623016, 664.9069130321759, 670.7048898484651, 672.6939242644585, 687.2165731066588, 10010.8498372523, 10437.68686939393, 11341.334786325207, 11988.700282493008, 12152.270863748203, 654.5501713704073, 661.5170925224613, 662.68522451835, 674.7672053141658, 691.1429827014466, 5379.0, 5384.811531596375, 5504.6421982571, 5522.861928451741, 5621.747021383966, 108579.0, 109368.0, 110558.0, 110883.0, 111741.88258117477, 75751.0, 95615.0, 96196.0, 96665.51667241618, 98179.50910245403, 83222.58388708018, 83454.74817877737, 84212.0, 84580.73128903071, 86050.13949860547, 10845.295055821662, 10938.343699378063, 11241.831372529583, 11295.0, 11494.540218391505, 40171.79914100528, 40203.59796402116, 40510.594894051865, 40553.78338649845, 40918.38622420726, 43391.78304108459, 52036.479635204676, 52116.0, 52649.47350526599, 52916.0, 13942.721145576828, 14059.297035147996, 14256.215908124737, 14343.0, 14356.712865742418, 12889.871101289242, 14956.626084347978, 15111.54665360041, 15636.843631563539, 15703.842961570239, 14243.501477448239, 14314.928425357806, 14350.067245629001, 14375.274967004027, 14501.129932204101, 13967.441302347792, 14430.567082987527, 14448.711025779636, 14565.27173641335, 14571.92714036423, 13880.305984700926, 14162.079464834753, 14235.50175743844, 14338.784918226444, 14416.197975253093, 9969.700908972742, 10140.949295253477, 10218.0, 10342.793144136924, 10547.630832920811, 11089.445527723743, 11102.722431939266, 11185.32888026721, 11295.039921606756, 11303.7739245213], "value": 8063.590428940024, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 145.0, 168.0, 170.0, 113.0, 114.0, 117.0, 113.0, 113.0, 114.0], "value": 128.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 126.0, 145.0, 114.0, 99.0, 144.0, 143.0, 162.0, 107.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 229.0, 242.0, 221.0, 231.0, 277.0, 277.0, 201.0, 198.0, 198.0], "value": 227.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 53.0, 65.0, 112.0, 83.0, 84.0, 71.0, 86.0, 90.0, 49.0], "value": 77.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 73.0, 103.0, 68.0, 68.0, 73.0, 88.0, 70.0, 69.0, 91.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 91.0, 89.0, 89.0, 89.0, 90.0, 97.0, 90.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 96.0, 149.0, 96.0, 97.0, 97.0, 96.0, 97.0, 98.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 89.0, 78.0, 146.0, 145.0, 90.0, 92.0, 119.0, 149.0], "value": 109.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 53.0, 51.0, 51.0, 52.0, 51.0, 51.0, 49.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 63.0, 80.0, 67.0, 63.0, 57.0, 59.0, 49.0, 77.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 81.0, 85.0, 78.0, 97.0, 89.0, 84.0, 82.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 146.0, 140.0, 140.0, 135.0, 138.0, 163.0, 155.0, 204.0, 178.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 189.0, 187.0, 188.0, 189.0, 187.0, 184.0, 187.0, 241.0], "value": 192.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 70.0, 67.0, 71.0, 66.0, 70.0, 68.0, 70.0, 82.0], "value": 70.0, "unit": "score"}]}]} 05:02:51 INFO - Return code: 0 05:02:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:02:51 INFO - # TBPL SUCCESS # 05:02:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:02:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:02:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:02:51 INFO - 2017-05-06 05:02:51.293 screenresolution[2063:24127] starting screenresolution argv=screenresolution get 05:02:51 INFO - 2017-05-06 05:02:51.296 screenresolution[2063:24127] Display 0: 1600x1200x32@60 05:02:51 INFO - 2017-05-06 05:02:51.302 screenresolution[2064:24130] starting screenresolution argv=screenresolution list 05:02:51 INFO - Available Modes on Display 0 05:02:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:02:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:02:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:02:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:02:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:02:51 INFO - Intel Iris: 05:02:51 INFO - Chipset Model: Intel Iris 05:02:51 INFO - Type: GPU 05:02:51 INFO - Bus: Built-In 05:02:51 INFO - VRAM (Dynamic, Max): 1536 MB 05:02:51 INFO - Vendor: Intel (0x8086) 05:02:51 INFO - Device ID: 0x0a2e 05:02:51 INFO - Revision ID: 0x0009 05:02:51 INFO - Displays: 05:02:51 INFO - Display: 05:02:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:02:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:02:51 INFO - Display Serial Number: 1600x1200 60 05:02:51 INFO - Main Display: Yes 05:02:51 INFO - Mirror: Off 05:02:51 INFO - Online: Yes 05:02:51 INFO - Rotation: Supported 05:02:51 INFO - Return code: 0 05:02:51 INFO - Running post-action listener: _resource_record_post_action 05:02:51 INFO - [mozharness: 2017-05-06 12:02:51.542786Z] Finished run-tests step (success) 05:02:51 INFO - Running post-run listener: _resource_record_post_run 05:02:51 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 1900544; Write bytes: 509102080; Read time: 13; Write time: 1638 05:02:51 INFO - TinderboxPrint: CPU usage
54.1% 05:02:51 INFO - TinderboxPrint: I/O read bytes / time
1,900,544 / 13 05:02:51 INFO - TinderboxPrint: I/O write bytes / time
509,102,080 / 1,638 05:02:51 INFO - TinderboxPrint: CPU idle
2,200.8 (45.9%) 05:02:51 INFO - TinderboxPrint: CPU system
962.5 (20.1%) 05:02:51 INFO - TinderboxPrint: CPU user
1,626.3 (34.0%) 05:02:51 INFO - TinderboxPrint: Swap in / out
209,481,728 / 0 05:02:51 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194628096; Write bytes: 192487424; Read time: 15036; Write time: 377 05:02:51 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1900544; Write bytes: 299767808; Read time: 13; Write time: 1210 05:02:52 INFO - Running post-run listener: _upload_blobber_files 05:02:52 INFO - Blob upload gear active. 05:02:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:02:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:02:52 INFO - (blobuploader) - INFO - Open directory for files ... 05:02:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:02:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:02:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:53 INFO - (blobuploader) - INFO - Done attempting. 05:02:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:02:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:02:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:54 INFO - (blobuploader) - INFO - Done attempting. 05:02:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:02:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:02:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:55 INFO - (blobuploader) - INFO - Done attempting. 05:02:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:02:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:02:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:02:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:02:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:02:57 INFO - (blobuploader) - INFO - Done attempting. 05:02:57 INFO - (blobuploader) - INFO - Iteration through files over. 05:02:57 INFO - Return code: 0 05:02:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:02:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:02:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/070ed8e338fd7128574fbb5bf7466f2db2d4e9a797fe633d5da5b50d020e204c0e51be8909074c19ad1e253438584ed833c8e672204dccd793f065e3e190863a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c1162f3bf8f652d25b8e5b223814c024091d375e9f04d1d56ccf93b3ea0b2e81b428f743d5cbc5fa7f4ce9aeb2ad3fc14e4d4849fd03e215864c6be9ad26c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fdc0821efff94b17ec5e00b65d290f71451fcfa3c866c21be6863cdfc7bb4adbb6c104191baeb5ee92188311d33c910f71002c394765a31783b9a7ecee550fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1c07349f8016d8b4cd519945951ba0d5a5ab6786ccd32f4f1df4ecc438e7cb0ff05e8942e76991b194afdcb9bac0c717edbdecc7f702ab30cf60175830d03e"} 05:02:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:02:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:02:57 INFO - Contents: 05:02:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/070ed8e338fd7128574fbb5bf7466f2db2d4e9a797fe633d5da5b50d020e204c0e51be8909074c19ad1e253438584ed833c8e672204dccd793f065e3e190863a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c1162f3bf8f652d25b8e5b223814c024091d375e9f04d1d56ccf93b3ea0b2e81b428f743d5cbc5fa7f4ce9aeb2ad3fc14e4d4849fd03e215864c6be9ad26c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fdc0821efff94b17ec5e00b65d290f71451fcfa3c866c21be6863cdfc7bb4adbb6c104191baeb5ee92188311d33c910f71002c394765a31783b9a7ecee550fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1c07349f8016d8b4cd519945951ba0d5a5ab6786ccd32f4f1df4ecc438e7cb0ff05e8942e76991b194afdcb9bac0c717edbdecc7f702ab30cf60175830d03e"} 05:02:57 INFO - Running post-run listener: copy_logs_to_upload_dir 05:02:57 INFO - Copying logs to upload dir... 05:02:57 INFO - mkdir: /builds/slave/test/build/upload/logs 05:02:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.306595 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-06 05:02:57.975549) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-06 05:02:57.979607) ========= 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.6UwdKrlCHf/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.DqVKeennOS/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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/070ed8e338fd7128574fbb5bf7466f2db2d4e9a797fe633d5da5b50d020e204c0e51be8909074c19ad1e253438584ed833c8e672204dccd793f065e3e190863a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c1162f3bf8f652d25b8e5b223814c024091d375e9f04d1d56ccf93b3ea0b2e81b428f743d5cbc5fa7f4ce9aeb2ad3fc14e4d4849fd03e215864c6be9ad26c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fdc0821efff94b17ec5e00b65d290f71451fcfa3c866c21be6863cdfc7bb4adbb6c104191baeb5ee92188311d33c910f71002c394765a31783b9a7ecee550fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1c07349f8016d8b4cd519945951ba0d5a5ab6786ccd32f4f1df4ecc438e7cb0ff05e8942e76991b194afdcb9bac0c717edbdecc7f702ab30cf60175830d03e"} build_url:https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/FDNhugVSRz2mumw2p_j1Aw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/070ed8e338fd7128574fbb5bf7466f2db2d4e9a797fe633d5da5b50d020e204c0e51be8909074c19ad1e253438584ed833c8e672204dccd793f065e3e190863a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9c1162f3bf8f652d25b8e5b223814c024091d375e9f04d1d56ccf93b3ea0b2e81b428f743d5cbc5fa7f4ce9aeb2ad3fc14e4d4849fd03e215864c6be9ad26c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fdc0821efff94b17ec5e00b65d290f71451fcfa3c866c21be6863cdfc7bb4adbb6c104191baeb5ee92188311d33c910f71002c394765a31783b9a7ecee550fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f1c07349f8016d8b4cd519945951ba0d5a5ab6786ccd32f4f1df4ecc438e7cb0ff05e8942e76991b194afdcb9bac0c717edbdecc7f702ab30cf60175830d03e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-06 05:02:58.015398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 05:02:58.015710) ========= 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.6UwdKrlCHf/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.DqVKeennOS/Listeners TMPDIR=/var/folders/q_/kt_2x2c93ns8_3884fgvcjs400000w/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.005203 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 05:02:58.063862) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-06 05:02:58.064178) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-06 05:02:58.064495) ========= ========= Total master_lag: 0.18 =========