builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0127 starttime: 1491260402.91 results: success (0) buildid: 20170403145128 builduid: c3508c96add14290875cb09c8cb1f9ae revision: b5d8b27a753725c1de41ffae2e338798f3b5cacd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:02.905357) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:02.905805) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:02.906107) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:02.934712) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 16:00:02.935120) ========= 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.N5GflL1SUc/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.z7piX0uHxH/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-04-03 16:00:02-- 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% 155M=0s 2017-04-03 16:00:04 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.446373 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-03 16:00:04.408309) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:04.409132) ========= 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.N5GflL1SUc/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.z7piX0uHxH/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.021753 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:04.458954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-03 16:00:04.459590) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b5d8b27a753725c1de41ffae2e338798f3b5cacd --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 b5d8b27a753725c1de41ffae2e338798f3b5cacd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N5GflL1SUc/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.z7piX0uHxH/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-04-03 16:00:04,544 truncating revision to first 12 chars 2017-04-03 16:00:04,544 Setting DEBUG logging. 2017-04-03 16:00:04,544 attempt 1/10 2017-04-03 16:00:04,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b5d8b27a7537?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 16:00:06,295 unpacking tar archive at: mozilla-central-b5d8b27a7537/testing/mozharness/ program finished with exit code 0 elapsedTime=2.015980 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-03 16:00:06.503162) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:06.503884) ========= script_repo_revision: b5d8b27a753725c1de41ffae2e338798f3b5cacd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:06.504417) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:06.504749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 16:00:06.526161) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-03 16:00:06.526503) ========= /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.N5GflL1SUc/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.z7piX0uHxH/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:00:06 INFO - MultiFileLogger online at 20170403 16:00:06 in /builds/slave/test 16:00:06 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 16:00:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:00:06 INFO - {'append_to_log': False, 16:00:06 INFO - 'base_work_dir': '/builds/slave/test', 16:00:06 INFO - 'blob_upload_branch': 'Firefox', 16:00:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:00:06 INFO - 'branch': 'Firefox', 16:00:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:00:06 INFO - 'config_files': ('talos/mac_config.py',), 16:00:06 INFO - 'default_actions': ('clobber', 16:00:06 INFO - 'read-buildbot-config', 16:00:06 INFO - 'download-and-extract', 16:00:06 INFO - 'populate-webroot', 16:00:06 INFO - 'create-virtualenv', 16:00:06 INFO - 'install', 16:00:06 INFO - 'run-tests'), 16:00:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:00:06 INFO - 'download_minidump_stackwalk': True, 16:00:06 INFO - 'download_symbols': 'ondemand', 16:00:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:00:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:00:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:00:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:00:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:00:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:00:06 INFO - 'gecko_profile': False, 16:00:06 INFO - 'gecko_profile_interval': 0, 16:00:06 INFO - 'installer_path': 'installer.exe', 16:00:06 INFO - 'log_level': 'info', 16:00:06 INFO - 'log_name': 'talos', 16:00:06 INFO - 'log_to_console': True, 16:00:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:00:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:00:06 INFO - 'opt_config_files': (), 16:00:06 INFO - 'pip_index': False, 16:00:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:06 INFO - 'cmd': ('bash', 16:00:06 INFO - '-c', 16:00:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:06 INFO - 'enabled': True, 16:00:06 INFO - 'halt_on_failure': False, 16:00:06 INFO - 'name': 'check_screen_resolution'},), 16:00:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:00:06 INFO - 'cmd': ('bash', 16:00:06 INFO - '-c', 16:00:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:00:06 INFO - 'enabled': True, 16:00:06 INFO - 'halt_on_failure': False, 16:00:06 INFO - 'name': 'check_screen_resolution'},), 16:00:06 INFO - 'run_cmd_checks_enabled': True, 16:00:06 INFO - 'suite': 'dromaeojs-e10s', 16:00:06 INFO - 'system_bits': '32', 16:00:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:00:06 INFO - 'title': 't-yosemite-r7-0127', 16:00:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:00:06 INFO - 'use_talos_json': True, 16:00:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:00:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:00:06 INFO - 'work_dir': 'build'} 16:00:06 INFO - [mozharness: 2017-04-03 23:00:06.738557Z] Running clobber step. 16:00:06 INFO - Running pre-action listener: _resource_record_pre_action 16:00:06 INFO - Running main action method: clobber 16:00:06 INFO - rmtree: /builds/slave/test/build 16:00:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:00:08 INFO - Running post-action listener: _resource_record_post_action 16:00:08 INFO - [mozharness: 2017-04-03 23:00:08.626463Z] Finished clobber step (success) 16:00:08 INFO - [mozharness: 2017-04-03 23:00:08.626596Z] Running read-buildbot-config step. 16:00:08 INFO - Running pre-action listener: _resource_record_pre_action 16:00:08 INFO - Running main action method: read_buildbot_config 16:00:08 INFO - Using buildbot properties: 16:00:08 INFO - { 16:00:08 INFO - "project": "", 16:00:08 INFO - "product": "firefox", 16:00:08 INFO - "script_repo_revision": "production", 16:00:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:00:08 INFO - "repository": "", 16:00:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:00:08 INFO - "buildid": "20170403145128", 16:00:08 INFO - "pgo_build": "False", 16:00:08 INFO - "basedir": "/builds/slave/test", 16:00:08 INFO - "buildnumber": 19, 16:00:08 INFO - "slavename": "t-yosemite-r7-0127", 16:00:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:00:08 INFO - "platform": "yosemite_r7", 16:00:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:00:08 INFO - "branch": "mozilla-central", 16:00:08 INFO - "repo_path": "mozilla-central", 16:00:08 INFO - "revision": "b5d8b27a753725c1de41ffae2e338798f3b5cacd", 16:00:08 INFO - "stage_platform": "macosx64", 16:00:08 INFO - "builduid": "c3508c96add14290875cb09c8cb1f9ae", 16:00:08 INFO - "slavebuilddir": "test" 16:00:08 INFO - } 16:00:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:00:08 INFO - Running post-action listener: _resource_record_post_action 16:00:08 INFO - [mozharness: 2017-04-03 23:00:08.630433Z] Finished read-buildbot-config step (success) 16:00:08 INFO - [mozharness: 2017-04-03 23:00:08.630570Z] Running download-and-extract step. 16:00:08 INFO - Running pre-action listener: _resource_record_pre_action 16:00:08 INFO - Running main action method: download_and_extract 16:00:08 INFO - mkdir: /builds/slave/test/build/tests 16:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:08 INFO - trying https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:00:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/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 16:00:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/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 16:00:09 INFO - Downloaded 1325 bytes. 16:00:09 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:00:09 INFO - Using the following test package requirements: 16:00:09 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:00:09 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:00:09 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:00:09 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:00:09 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'jsshell-mac.zip'], 16:00:09 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:00:09 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:00:09 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:00:09 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:00:09 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:00:09 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:00:09 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:00:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:00:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:00:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:00:09 INFO - Fetch https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:00:11 INFO - Expected file size: 52515985 16:00:11 INFO - Obtained file size: 52515985 16:00:27 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 16:00:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:00:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:00:27 INFO - Fetch https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:00:29 INFO - Expected file size: 52515985 16:00:29 INFO - Obtained file size: 52515985 16:00:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:00:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:00:44 INFO - Fetch https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:00:45 INFO - Expected file size: 14404152 16:00:45 INFO - Obtained file size: 14404152 16:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:46 INFO - trying https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:00:46 INFO - Downloading https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/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 16:00:48 INFO - Downloaded 75572740 bytes. 16:00:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:00:48 INFO - mkdir: /builds/slave/test/properties 16:00:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:00:48 INFO - Writing to file /builds/slave/test/properties/build_url 16:00:48 INFO - Contents: 16:00:48 INFO - build_url:https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:00:48 INFO - Running post-action listener: _resource_record_post_action 16:00:48 INFO - Running post-action listener: set_extra_try_arguments 16:00:48 INFO - [mozharness: 2017-04-03 23:00:48.792627Z] Finished download-and-extract step (success) 16:00:48 INFO - [mozharness: 2017-04-03 23:00:48.792760Z] Running populate-webroot step. 16:00:48 INFO - Running pre-action listener: _resource_record_pre_action 16:00:48 INFO - Running main action method: populate_webroot 16:00:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:48 INFO - '--tppagecycles', 16:00:48 INFO - '7'], 16:00:48 INFO - 'tests': ['tsvgm']}}, 16:00:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:00:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:00:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:00:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['damp', 'tps']}, 16:00:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'tests': ['damp', 'tps']}, 16:00:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['dromaeo_dom']}, 16:00:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['basic_compositor_video']}, 16:00:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:00:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['a11yr', 16:00:48 INFO - 'ts_paint', 16:00:48 INFO - 'tpaint', 16:00:48 INFO - 'sessionrestore', 16:00:48 INFO - 'sessionrestore_no_auto_restore', 16:00:48 INFO - 'tabpaint']}, 16:00:48 INFO - 'other-e10s': {'tests': ['a11yr', 16:00:48 INFO - 'ts_paint', 16:00:48 INFO - 'tpaint', 16:00:48 INFO - 'sessionrestore', 16:00:48 INFO - 'sessionrestore_no_auto_restore', 16:00:48 INFO - 'tabpaint']}, 16:00:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:00:48 INFO - 'ts_paint', 16:00:48 INFO - 'tpaint', 16:00:48 INFO - 'sessionrestore', 16:00:48 INFO - 'sessionrestore_no_auto_restore', 16:00:48 INFO - 'tabpaint']}, 16:00:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:00:48 INFO - 'ts_paint', 16:00:48 INFO - 'tpaint', 16:00:48 INFO - 'sessionrestore', 16:00:48 INFO - 'sessionrestore_no_auto_restore', 16:00:48 INFO - 'tabpaint']}, 16:00:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['a11yr', 16:00:48 INFO - 'ts_paint', 16:00:48 INFO - 'tpaint', 16:00:48 INFO - 'sessionrestore', 16:00:48 INFO - 'sessionrestore_no_auto_restore', 16:00:48 INFO - 'tabpaint']}, 16:00:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['a11yr', 16:00:48 INFO - 'ts_paint', 16:00:48 INFO - 'tpaint', 16:00:48 INFO - 'sessionrestore', 16:00:48 INFO - 'sessionrestore_no_auto_restore', 16:00:48 INFO - 'tabpaint']}, 16:00:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['tsvgx', 16:00:48 INFO - 'tsvgr_opacity', 16:00:48 INFO - 'tart', 16:00:48 INFO - 'tscrollx', 16:00:48 INFO - 'cart', 16:00:48 INFO - 'tsvg_static']}, 16:00:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:00:48 INFO - 'tsvgr_opacity', 16:00:48 INFO - 'tart', 16:00:48 INFO - 'tscrollx', 16:00:48 INFO - 'cart', 16:00:48 INFO - 'tsvg_static']}, 16:00:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'talos_options': ['--disable-e10s'], 16:00:48 INFO - 'tests': ['tp5o']}, 16:00:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:00:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'talos_options': ['--disable-e10s', 16:00:48 INFO - '--xperf_path', 16:00:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:48 INFO - 'tests': ['tp5n']}, 16:00:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:00:48 INFO - 'talos_options': ['--xperf_path', 16:00:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:48 INFO - 'tests': ['tp5n']}}, 16:00:48 INFO - 'talos.zip': {'path': '', 16:00:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:48 INFO - Running post-action listener: _resource_record_post_action 16:00:48 INFO - [mozharness: 2017-04-03 23:00:48.811653Z] Finished populate-webroot step (success) 16:00:48 INFO - [mozharness: 2017-04-03 23:00:48.811763Z] Running create-virtualenv step. 16:00:48 INFO - Running pre-action listener: _resource_record_pre_action 16:00:48 INFO - Running main action method: create_virtualenv 16:00:48 INFO - Creating virtualenv /builds/slave/test/build/venv 16:00:48 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 16:00:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:00:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:48 INFO - Using real prefix '/tools/python27' 16:00:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:00:49 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:52 INFO - Installing pip.................done. 16:00:52 INFO - Return code: 0 16:00:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:00:52 INFO - Reading from file tmpfile_stdout 16:00:52 INFO - Output received: 16:00:52 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) 16:00:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:00: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')]} 16:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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')]} 16:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:00: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', 'pip>=1.5'] in /builds/slave/test/build 16:00: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 pip>=1.5 16:00:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:00:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:52 INFO - 'HOME': '/Users/cltbld', 16:00:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:52 INFO - 'LOGNAME': 'cltbld', 16:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:52 INFO - 'MOZ_NO_REMOTE': '1', 16:00:52 INFO - 'NO_EM_RESTART': '1', 16:00:52 INFO - 'PAGER': '/bin/cat', 16:00:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:52 INFO - 'PWD': '/builds/slave/test', 16:00:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:52 INFO - 'SHELL': '/bin/bash', 16:00:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:00:52 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:00:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:52 INFO - 'USER': 'cltbld', 16:00:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:52 INFO - 'XPC_FLAGS': '0x0', 16:00:52 INFO - 'XPC_SERVICE_NAME': '0', 16:00:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:53 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 16:00:53 INFO - Cleaning up... 16:00:53 INFO - Return code: 0 16:00:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:53 INFO - 'HOME': '/Users/cltbld', 16:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:53 INFO - 'LOGNAME': 'cltbld', 16:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:53 INFO - 'MOZ_NO_REMOTE': '1', 16:00:53 INFO - 'NO_EM_RESTART': '1', 16:00:53 INFO - 'PAGER': '/bin/cat', 16:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:53 INFO - 'PWD': '/builds/slave/test', 16:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:53 INFO - 'SHELL': '/bin/bash', 16:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:00:53 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:53 INFO - 'USER': 'cltbld', 16:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:53 INFO - 'XPC_FLAGS': '0x0', 16:00:53 INFO - 'XPC_SERVICE_NAME': '0', 16:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:53 INFO - Downloading/unpacking psutil>=3.1.1 16:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:56 INFO - Installing collected packages: psutil 16:00:56 INFO - Running setup.py install for psutil 16:00:57 INFO - building 'psutil._psutil_osx' extension 16:00:57 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 16:00:57 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 16:00:57 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 16:00:57 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 16:00:57 INFO - building 'psutil._psutil_posix' extension 16:00:57 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 16:00:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:00:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:00:57 INFO - ^ 16:00:57 INFO - 1 warning generated. 16:00:57 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 16:00:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:57 INFO - Successfully installed psutil 16:00:57 INFO - Cleaning up... 16:00:57 INFO - Return code: 0 16:00:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:58 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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:00:58 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 16:00:58 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 16:00:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:00:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:58 INFO - 'HOME': '/Users/cltbld', 16:00:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:58 INFO - 'LOGNAME': 'cltbld', 16:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:58 INFO - 'MOZ_NO_REMOTE': '1', 16:00:58 INFO - 'NO_EM_RESTART': '1', 16:00:58 INFO - 'PAGER': '/bin/cat', 16:00:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:58 INFO - 'PWD': '/builds/slave/test', 16:00:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:58 INFO - 'SHELL': '/bin/bash', 16:00:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:00:58 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:00:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:58 INFO - 'USER': 'cltbld', 16:00:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:58 INFO - 'XPC_FLAGS': '0x0', 16:00:58 INFO - 'XPC_SERVICE_NAME': '0', 16:00:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:01 INFO - Installing collected packages: mozsystemmonitor 16:01:01 INFO - Running setup.py install for mozsystemmonitor 16:01:01 INFO - Successfully installed mozsystemmonitor 16:01:01 INFO - Cleaning up... 16:01:01 INFO - Return code: 0 16:01:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:01 INFO - 'HOME': '/Users/cltbld', 16:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:01 INFO - 'LOGNAME': 'cltbld', 16:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:01 INFO - 'MOZ_NO_REMOTE': '1', 16:01:01 INFO - 'NO_EM_RESTART': '1', 16:01:01 INFO - 'PAGER': '/bin/cat', 16:01:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:01 INFO - 'PWD': '/builds/slave/test', 16:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:01 INFO - 'SHELL': '/bin/bash', 16:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:01 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:01 INFO - 'USER': 'cltbld', 16:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:01 INFO - 'XPC_FLAGS': '0x0', 16:01:01 INFO - 'XPC_SERVICE_NAME': '0', 16:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:02 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:02 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 16:01:02 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 16:01:02 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 16:01:02 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 16:01:02 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 16:01:02 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 16:01:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:05 INFO - warning: no files found matching '*.txt' 16:01:05 INFO - no previously-included directories found matching 'build' 16:01:05 INFO - no previously-included directories found matching 'dist' 16:01:05 INFO - no previously-included directories found matching '.git*' 16:01:05 INFO - Installing collected packages: jsonschema, functools32 16:01:05 INFO - Running setup.py install for functools32 16:01:05 INFO - warning: no files found matching '*.txt' 16:01:05 INFO - no previously-included directories found matching 'build' 16:01:05 INFO - no previously-included directories found matching 'dist' 16:01:05 INFO - no previously-included directories found matching '.git*' 16:01:05 INFO - Successfully installed jsonschema functools32 16:01:05 INFO - Cleaning up... 16:01:05 INFO - Return code: 0 16:01:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:01:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:05 INFO - 'HOME': '/Users/cltbld', 16:01:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:05 INFO - 'LOGNAME': 'cltbld', 16:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:05 INFO - 'MOZ_NO_REMOTE': '1', 16:01:05 INFO - 'NO_EM_RESTART': '1', 16:01:05 INFO - 'PAGER': '/bin/cat', 16:01:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:05 INFO - 'PWD': '/builds/slave/test', 16:01:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:05 INFO - 'SHELL': '/bin/bash', 16:01:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:05 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:05 INFO - 'USER': 'cltbld', 16:01:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:05 INFO - 'XPC_FLAGS': '0x0', 16:01:05 INFO - 'XPC_SERVICE_NAME': '0', 16:01:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:05 INFO - Cleaning up... 16:01:05 INFO - Return code: 0 16:01:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:01:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:05 INFO - 'HOME': '/Users/cltbld', 16:01:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:05 INFO - 'LOGNAME': 'cltbld', 16:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:05 INFO - 'MOZ_NO_REMOTE': '1', 16:01:05 INFO - 'NO_EM_RESTART': '1', 16:01:05 INFO - 'PAGER': '/bin/cat', 16:01:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:05 INFO - 'PWD': '/builds/slave/test', 16:01:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:05 INFO - 'SHELL': '/bin/bash', 16:01:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:05 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:05 INFO - 'USER': 'cltbld', 16:01:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:05 INFO - 'XPC_FLAGS': '0x0', 16:01:05 INFO - 'XPC_SERVICE_NAME': '0', 16:01:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:05 INFO - Downloading/unpacking blobuploader==1.2.4 16:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:08 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:09 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 16:01:09 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 16:01:09 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 16:01:09 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 16:01:09 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 16:01:09 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 16:01:09 INFO - Downloading docopt-0.6.1.tar.gz 16:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:09 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:09 INFO - Running setup.py install for blobuploader 16:01:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:10 INFO - Running setup.py install for requests 16:01:10 INFO - Running setup.py install for docopt 16:01:10 INFO - Successfully installed blobuploader requests docopt 16:01:10 INFO - Cleaning up... 16:01:10 INFO - Return code: 0 16:01:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:10 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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:10 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 16:01:10 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 16:01:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:10 INFO - 'HOME': '/Users/cltbld', 16:01:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:10 INFO - 'LOGNAME': 'cltbld', 16:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:10 INFO - 'MOZ_NO_REMOTE': '1', 16:01:10 INFO - 'NO_EM_RESTART': '1', 16:01:10 INFO - 'PAGER': '/bin/cat', 16:01:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:10 INFO - 'PWD': '/builds/slave/test', 16:01:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:10 INFO - 'SHELL': '/bin/bash', 16:01:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:10 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:10 INFO - 'USER': 'cltbld', 16:01:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:10 INFO - 'XPC_FLAGS': '0x0', 16:01:10 INFO - 'XPC_SERVICE_NAME': '0', 16:01:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-kmT7zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-nGBIgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Dt7O2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-yk5gw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Bt5C0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-q3Nwo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZmiosT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-lY9WwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:11 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-B5DMB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Tr2u8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-mcrNIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-e6q4Vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-tXmiE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-XY_d3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-maqWVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-sWIoXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:12 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-S7UfuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:12 INFO - Running setup.py install for manifestparser 16:01:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:13 INFO - Running setup.py install for mozcrash 16:01:13 INFO - Running setup.py install for mozdebug 16:01:13 INFO - Running setup.py install for mozdevice 16:01:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:13 INFO - Running setup.py install for mozfile 16:01:13 INFO - Running setup.py install for mozhttpd 16:01:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:13 INFO - Running setup.py install for mozinfo 16:01:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:01:13 INFO - Running setup.py install for mozInstall 16:01:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Running setup.py install for mozleak 16:01:14 INFO - Running setup.py install for mozlog 16:01:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Running setup.py install for moznetwork 16:01:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Running setup.py install for mozprocess 16:01:14 INFO - Running setup.py install for mozprofile 16:01:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:01:14 INFO - Running setup.py install for mozrunner 16:01:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:01:15 INFO - Running setup.py install for mozscreenshot 16:01:15 INFO - Running setup.py install for moztest 16:01:15 INFO - Running setup.py install for mozversion 16:01:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:01:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:01:15 INFO - Cleaning up... 16:01:15 INFO - Return code: 0 16:01:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:15 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')]} 16:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:15 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')]} 16:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:15 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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:15 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 16:01:15 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 16:01:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:15 INFO - 'HOME': '/Users/cltbld', 16:01:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:15 INFO - 'LOGNAME': 'cltbld', 16:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:15 INFO - 'MOZ_NO_REMOTE': '1', 16:01:15 INFO - 'NO_EM_RESTART': '1', 16:01:15 INFO - 'PAGER': '/bin/cat', 16:01:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:15 INFO - 'PWD': '/builds/slave/test', 16:01:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:15 INFO - 'SHELL': '/bin/bash', 16:01:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:15 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:15 INFO - 'USER': 'cltbld', 16:01:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:15 INFO - 'XPC_FLAGS': '0x0', 16:01:15 INFO - 'XPC_SERVICE_NAME': '0', 16:01:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:15 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-0Jb1BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:15 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)) 16:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:15 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-qJBr_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:15 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)) 16:01:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:15 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-0iB3VS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-8U451m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZEu6Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-0r9oNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-hyH3FI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-3Xa07Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-_JtbKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-GFu0pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-3NscN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:16 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)) 16:01:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:16 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-objwUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:17 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)) 16:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-QXn0m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:17 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)) 16:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-f3rY6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:17 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)) 16:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Qn4RcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:17 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)) 16:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-d6TNyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:17 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)) 16:01:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:17 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-N26_89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:17 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)) 16:01:17 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)) 16:01:17 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)) 16:01:17 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)) 16:01:17 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:01:17 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:01:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:20 INFO - Downloading blessings-1.6.tar.gz 16:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:01:20 INFO - Installing collected packages: blessings 16:01:20 INFO - Running setup.py install for blessings 16:01:20 INFO - Successfully installed blessings 16:01:20 INFO - Cleaning up... 16:01:20 INFO - Return code: 0 16:01:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:01:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:20 INFO - Reading from file tmpfile_stdout 16:01:20 INFO - Current package versions: 16:01:20 INFO - blessings == 1.6 16:01:20 INFO - blobuploader == 1.2.4 16:01:20 INFO - docopt == 0.6.1 16:01:20 INFO - functools32 == 3.2.3-2 16:01:20 INFO - jsonschema == 2.5.1 16:01:20 INFO - manifestparser == 1.1 16:01:20 INFO - mozInstall == 1.12 16:01:20 INFO - mozcrash == 1.0 16:01:20 INFO - mozdebug == 0.1 16:01:20 INFO - mozdevice == 0.49 16:01:20 INFO - mozfile == 1.2 16:01:20 INFO - mozhttpd == 0.7 16:01:20 INFO - mozinfo == 0.9 16:01:20 INFO - mozleak == 0.1 16:01:20 INFO - mozlog == 3.4 16:01:20 INFO - moznetwork == 0.27 16:01:20 INFO - mozprocess == 0.25 16:01:20 INFO - mozprofile == 0.28 16:01:20 INFO - mozrunner == 6.13 16:01:20 INFO - mozscreenshot == 0.1 16:01:20 INFO - mozsystemmonitor == 0.3 16:01:20 INFO - moztest == 0.8 16:01:20 INFO - mozversion == 1.4 16:01:20 INFO - psutil == 3.1.1 16:01:20 INFO - requests == 1.2.3 16:01:20 INFO - wsgiref == 0.1.2 16:01:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:20 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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:20 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 16:01:20 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 16:01:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:20 INFO - 'HOME': '/Users/cltbld', 16:01:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:20 INFO - 'LOGNAME': 'cltbld', 16:01:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:20 INFO - 'MOZ_NO_REMOTE': '1', 16:01:20 INFO - 'NO_EM_RESTART': '1', 16:01:20 INFO - 'PAGER': '/bin/cat', 16:01:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:20 INFO - 'PWD': '/builds/slave/test', 16:01:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:20 INFO - 'SHELL': '/bin/bash', 16:01:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:20 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:20 INFO - 'USER': 'cltbld', 16:01:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:20 INFO - 'XPC_FLAGS': '0x0', 16:01:20 INFO - 'XPC_SERVICE_NAME': '0', 16:01:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 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)) 16:01:21 INFO - Cleaning up... 16:01:21 INFO - Return code: 0 16:01:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:01:21 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')]} 16:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:21 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')]} 16:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:01:21 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 0x10a266b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a269768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff69b58e470>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, '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.N5GflL1SUc/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.z7piX0uHxH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 16:01:21 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 16:01:21 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 16:01:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:21 INFO - 'HOME': '/Users/cltbld', 16:01:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:21 INFO - 'LOGNAME': 'cltbld', 16:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:21 INFO - 'MOZ_NO_REMOTE': '1', 16:01:21 INFO - 'NO_EM_RESTART': '1', 16:01:21 INFO - 'PAGER': '/bin/cat', 16:01:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:21 INFO - 'PWD': '/builds/slave/test', 16:01:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:21 INFO - 'SHELL': '/bin/bash', 16:01:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:21 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:21 INFO - 'USER': 'cltbld', 16:01:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:21 INFO - 'XPC_FLAGS': '0x0', 16:01:21 INFO - 'XPC_SERVICE_NAME': '0', 16:01:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:01:21 INFO - Cleaning up... 16:01:21 INFO - Return code: 0 16:01:21 INFO - Running post-action listener: _resource_record_post_action 16:01:21 INFO - Running post-action listener: _start_resource_monitoring 16:01:21 INFO - Starting resource monitoring. 16:01:21 INFO - [mozharness: 2017-04-03 23:01:21.412803Z] Finished create-virtualenv step (success) 16:01:21 INFO - [mozharness: 2017-04-03 23:01:21.413799Z] Running install step. 16:01:21 INFO - Running pre-action listener: _resource_record_pre_action 16:01:21 INFO - Running main action method: install 16:01:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:01:21 INFO - Reading from file tmpfile_stdout 16:01:21 INFO - Detecting whether we're running mozinstall >=1.0... 16:01:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:01:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:01:21 INFO - Reading from file tmpfile_stdout 16:01:21 INFO - Output received: 16:01:21 INFO - Usage: mozinstall [options] installer 16:01:21 INFO - Options: 16:01:21 INFO - -h, --help show this help message and exit 16:01:21 INFO - -d DEST, --destination=DEST 16:01:21 INFO - Directory to install application into. [default: 16:01:21 INFO - "/builds/slave/test"] 16:01:21 INFO - --app=APP Application being installed. [default: firefox] 16:01:21 INFO - mkdir: /builds/slave/test/build/application 16:01:21 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'] 16:01:21 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 16:01:42 INFO - Reading from file tmpfile_stdout 16:01:42 INFO - Output received: 16:01:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:01:42 INFO - Running post-action listener: _resource_record_post_action 16:01:42 INFO - [mozharness: 2017-04-03 23:01:42.543731Z] Finished install step (success) 16:01:42 INFO - [mozharness: 2017-04-03 23:01:42.543835Z] Running run-tests step. 16:01:42 INFO - Running pre-action listener: _resource_record_pre_action 16:01:42 INFO - Running main action method: run_tests 16:01:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:01:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:01:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:01:42 INFO - 2017-04-03 16:01:42.558 screenresolution[3021:21829] starting screenresolution argv=screenresolution get 16:01:42 INFO - 2017-04-03 16:01:42.561 screenresolution[3021:21829] Display 0: 1600x1200x32@60 16:01:42 INFO - 2017-04-03 16:01:42.567 screenresolution[3022:21832] starting screenresolution argv=screenresolution list 16:01:42 INFO - Available Modes on Display 0 16:01:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:01:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:01:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:01:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:01:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:01:42 INFO - Intel Iris: 16:01:42 INFO - Chipset Model: Intel Iris 16:01:42 INFO - Type: GPU 16:01:42 INFO - Bus: Built-In 16:01:42 INFO - VRAM (Dynamic, Max): 1536 MB 16:01:42 INFO - Vendor: Intel (0x8086) 16:01:42 INFO - Device ID: 0x0a2e 16:01:42 INFO - Revision ID: 0x0009 16:01:42 INFO - Displays: 16:01:42 INFO - Display: 16:01:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:01:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:01:42 INFO - Display Serial Number: 1600x1200 60 16:01:42 INFO - Main Display: Yes 16:01:42 INFO - Mirror: Off 16:01:42 INFO - Online: Yes 16:01:42 INFO - Rotation: Supported 16:01:42 INFO - Return code: 0 16:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:01:42 INFO - Python 2.7.3 16:01:42 INFO - Return code: 0 16:01:42 INFO - grabbing minidump binary from tooltool 16:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:42 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 0x10a044d30>, '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 0x109f6bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a039be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:01:42 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 16:01:42 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 16:01:42 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 16:01:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:01:42 INFO - Return code: 0 16:01:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:01:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:01:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:01:42 INFO - ENV: RUST_BACKTRACE is now 1 16:01:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:01:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 16:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 16:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N5GflL1SUc/Render', 16:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:01:42 INFO - 'HOME': '/Users/cltbld', 16:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:01:42 INFO - 'LOGNAME': 'cltbld', 16:01:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:01:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:42 INFO - 'MOZ_NO_REMOTE': '1', 16:01:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:01:42 INFO - 'NO_EM_RESTART': '1', 16:01:42 INFO - 'PAGER': '/bin/cat', 16:01:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:42 INFO - 'PWD': '/builds/slave/test', 16:01:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:01:42 INFO - 'RUST_BACKTRACE': '1', 16:01:42 INFO - 'SHELL': '/bin/bash', 16:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z7piX0uHxH/Listeners', 16:01:42 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 16:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:01:42 INFO - 'USER': 'cltbld', 16:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:42 INFO - 'XPC_FLAGS': '0x0', 16:01:42 INFO - 'XPC_SERVICE_NAME': '0', 16:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:01:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 16:01:43 INFO - mozversion application_buildid: 20170403145128 16:01:43 INFO - mozversion application_changeset: b5d8b27a753725c1de41ffae2e338798f3b5cacd 16:01:43 INFO - mozversion application_display_name: Nightly 16:01:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:01:43 INFO - mozversion application_name: Firefox 16:01:43 INFO - mozversion application_remotingname: firefox 16:01:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:01:43 INFO - mozversion application_vendor: Mozilla 16:01:43 INFO - mozversion application_version: 55.0a1 16:01:43 INFO - mozversion platform_buildid: 20170403145128 16:01:43 INFO - mozversion platform_changeset: b5d8b27a753725c1de41ffae2e338798f3b5cacd 16:01:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:01:43 INFO - mozversion platform_version: 55.0a1 16:01:43 INFO - using testdate: 1491260503 16:01:43 INFO - actual date: 1491260503 16:01:43 INFO - starting webserver on 'localhost:53302' 16:01:43 INFO - SUITE-START | Running 2 tests 16:01:43 INFO - TEST-START | dromaeo_css 16:01:43 INFO - operating with platform_type : mac_ 16:01:43 INFO - Initialising browser for dromaeo_css test... 16:01:43 INFO - TEST-INFO | started process 3032 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpGCHSem/profile http://localhost:53302/getInfo.html) 16:01:45 INFO - PID 3032 | 2017-04-03 16:01:45.803 plugin-container[3034:22004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 16:01:45 INFO - PID 3032 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:45 INFO - PID 3032 | 2017-04-03 16:01:45.807 plugin-container[3034:22004] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 16:01:45 INFO - PID 3032 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:45 INFO - PID 3032 | 2017-04-03 16:01:45.807 plugin-container[3034:22004] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:01:45 INFO - PID 3032 | __metrics Screen width/height:1600/1200 16:01:45 INFO - PID 3032 | colorDepth:24 16:01:45 INFO - PID 3032 | Browser inner width/height: 1024/689 16:01:45 INFO - PID 3032 | __metrics 16:01:46 ERROR - PID 3032 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:01:46 INFO - TEST-INFO | 3032: exit 0 16:01:46 INFO - Browser initialized. 16:01:46 INFO - Running cycle 1/1 for dromaeo_css test... 16:01:46 INFO - TEST-INFO | started process 3035 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpGCHSem/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:49 INFO - PID 3035 | 2017-04-03 16:01:49.379 plugin-container[3036:22136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 16:01:49 INFO - PID 3035 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:49 INFO - PID 3035 | 2017-04-03 16:01:49.380 plugin-container[3036:22136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 16:01:49 INFO - PID 3035 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:49 INFO - PID 3035 | 2017-04-03 16:01:49.380 plugin-container[3036:22136] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:01:59 INFO - PID 3035 | RSS: Main: 229199872 16:01:59 INFO - PID 3035 | 16:05:02 INFO - PID 3035 | Cycle 1(1): loaded http://localhost:53302/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53302/tests/dromaeo/cssquery-ext.html) 16:05:04 INFO - PID 3035 | RSS: Main: 252272640 16:05:04 INFO - PID 3035 | 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | A coding exception was thrown and uncaught in a Task. 16:06:47 INFO - PID 3035 | 16:06:47 ERROR - PID 3035 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:06:47 INFO - PID 3035 | Full stack: 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | A coding exception was thrown and uncaught in a Task. 16:06:47 INFO - PID 3035 | 16:06:47 ERROR - PID 3035 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:06:47 INFO - PID 3035 | Full stack: 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | A coding exception was thrown and uncaught in a Task. 16:06:47 INFO - PID 3035 | 16:06:47 ERROR - PID 3035 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:06:47 INFO - PID 3035 | Full stack: 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | ************************* 16:06:47 INFO - PID 3035 | A coding exception was thrown and uncaught in a Task. 16:06:47 INFO - PID 3035 | 16:06:47 ERROR - PID 3035 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:06:47 INFO - PID 3035 | Full stack: 16:06:47 INFO - PID 3035 | ************************* 16:08:06 INFO - PID 3035 | Cycle 1(1): loaded http://localhost:53302/tests/dromaeo/cssquery-ext.html (next: http://localhost:53302/tests/dromaeo/cssquery-jquery.html) 16:08:07 INFO - PID 3035 | RSS: Main: 261943296 16:08:07 INFO - PID 3035 | 16:11:08 INFO - PID 3035 | Cycle 1(1): loaded http://localhost:53302/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53302/tests/dromaeo/cssquery-mootools.html) 16:11:09 INFO - PID 3035 | RSS: Main: 274911232 16:11:09 INFO - PID 3035 | 16:14:11 INFO - PID 3035 | Cycle 1(1): loaded http://localhost:53302/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53302/tests/dromaeo/cssquery-prototype.html) 16:14:12 INFO - PID 3035 | RSS: Main: 267190272 16:14:12 INFO - PID 3035 | 16:17:15 INFO - PID 3035 | Cycle 1(1): loaded http://localhost:53302/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53302/tests/dromaeo/cssquery-yui.html) 16:17:15 INFO - PID 3035 | RSS: Main: 277569536 16:17:15 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | Cycle 1(1): loaded http://localhost:53302/tests/dromaeo/cssquery-yui.html (next: http://localhost:53302/tests/dromaeo/cssquery-dojo.html) 16:20:19 INFO - PID 3035 | RSS: Main: 271491072 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | __start_tp_report 16:20:19 INFO - PID 3035 | _x_x_mozilla_page_load 16:20:19 INFO - PID 3035 | _x_x_mozilla_page_load_details 16:20:19 INFO - PID 3035 | |i|pagename|runs| 16:20:19 INFO - PID 3035 | |0;dojo.html;3527.6472352764727;3544.0785395797093;3553.9822300888522;3571.2321850802073;3581.355356035914;5011.974940125298;6064.818055458332;6218.657973811439;6230.968845155773;6259.593126446789;5538.113901775717;6058.697065146747;6063.514918806496;6083.422074902888;6151.8769624607485;5425.782968681248;5451.918221226685;5483.232347471358;5492.725363731817;5549.556035517149;5525.696086715229;5525.944740552603;5539;5539.362973258067;5543.25166102575;234.22545091371714;4793.616510679138;5318.962802253562;5336.199570064483;5348.411674715778;4473;4605.825514493816;5320.6275560710765;5419.485148910847;5438.129899216106;5392.325959255093;5396.568274538027;5398.2712333834825;5405.351357837046;5469.988052210314;4829.903401931977;5733.16869053989;6120.785772497986;6170.598911070766;6217.440430361289;5508.55931525477;6589.967050164766;6595.703193356262;6636.900446493307;6639.86720265592;6728.091707619457;6730.326967303279;6733.49498787588;6760.391564759196;6775.254721980578;6107.7957376609465;6155.138280640714;6171.012637977902;6173.660448675366;6228.314885362606;6866.141732283465;6899.551529150589;6905.654717264217;6914.446844252448;6932.688029038705;6502.60984340941;6522.576032557963;6555.770548030796;6570.375814297634;6574.572652777554;5421;6292.119103325538;6453.9354606453335;6469.482441404771;6659.167604049494;5522.30971128609;6322.367763223733;6493.610383377012;6590.143281373391;6602.207735071822;6564.67176641166;6570.211574611078;6582.308857569982;6582.3746744059235;6592.439642630334;5259.289995850588;5265;5296.867578310496;5314.867128321823;5363.2759577456545;4659.836905708352;4684.5549672781035;4712.9057418851435;4764.237721964469;4809.591184749265;6274.309825919146;6298.275698294755;6311.116443697886;6316;6322.810315690536;6573.638449885207;6684.064231007663;6685.899711504331;6688.230853451915;6695.46436285096;6150.04674275488;6207.317195108534;6228.03465462854;6287.245530536365;6289.937100629027;5193.636445448782;5840.036393995028;5939.910901336483;5961.076033214858;5961.791337303173;6818.318168183043;6842.863142737117;6861.348171923659;6876.071730316543;6879.484038697017;6422.743090276336;6490.967545162244;6662.700178492077;6719.596824190564;6735.2927942567285;6718.529702920749;6734.292899245509;6831.2144103428745;6849.657517124224;6851.931480685129;6558.672066396565;6576.473882089325;6585.506087043395;6586.209654841449;6589.769358072444;5068.771905264198;6336.683165841781;6691.665416729242;6696.966515167393;6697.430718388797;6287.559870808999;6386.297507274103;6400.39196276353;6407.743690252338;6446.613203207822;6471.385218404244;6478.319776423407;6480.513961453004;6484.1894763154605;6488.837779055561;6247.718852651732;6276.905846412216;6303.6532990685955;6321.399467050623;6357.4278314950025;5483;6465.676716164079;6472.8705425890275;6534.248561415498;6538.607683539003;6319.936800631935;6330.177076979963;6342.714577843916;6360.077788720517;6367.885620016571 16:20:19 INFO - PID 3035 | |1;ext.html;680.4522359500602;737.4469148138896;823.4071468542649;1279.60332296988;1291.4124073546532;37296.254074918485;38493.42259866104;38506;38513.42229866551;38533.614663853354;38316;38485.807570962104;38575.421368679425;38677.80661096691;38831;8088.555129467863;8255.917440825575;8515.318774498026;8733.868991965093;8751.081136480674;8603.612837422299;8663;8706.216440520351;8707.43401678892;8723.95638021809;8633.13668633138;8636.827263454727;8716.389852710312;8721.215090641841;8751.912480875173;8652.178043085896;8672.739817805475;8677.349198810052;8683.305335573139;8690.956545217297;7584.582847943361;8055.718049868226;8520.701775437892;8528.872066918939;8640.30877529796;37597.62402375969;37826.43260351097;37879.62120378789;38017.239655206744;38023;2987.775916806248;2996.5205567109156;2997.9457968224287;3007.804492707967;3010.277533391992;21338;28153.85923070392;28339.716602833912;28576;28682.426351473063;2818.6053952446673;2839.5172820620546;2840.7727381809405;2898.3478717288654;2941.3087924337765;247769.9999999982;296279;302126;302547;303504;39643.80178099149;48252;48369.06523739011;48488.7575562127;48512.757436212596;49900;49967.000659986596;50296.994060118595;50348.49651503438;50366;14543.927280363741;14651.974361794786;14658.486952956595;14670.486532971296;14670.779938300933;11886.762264754829;11929.701757456132;11932.642020739391;11946.462409191434;11949.98425133856;12470.688232794071;13272.469101236036;13278.601641950758;13334.733305333839;13365.529791722904;5879.911801322984;5903.881922361615;5914.85245280387;5926.970365148233;5976.372480889531;961.7451375385529;966.878421031614;971.8882328532168;972.4214092614883;1007.4106647611083;23861.642075368884;24171.154009609578;24230.213525444447;24235.151769688327;24306.513869722505;995.711243739322;1063.8244689626124;1068.58859339155;1107.5680484611007;1120.2942146447685;12144.271343719229;12273.69315767113;12276.956458700932;12332.568360107352;12336.629901102802;429128;432500;433094;434146;434497;51143.23285150726;52812.94374112496;52905.47094529006;53196.73401632967;53244.73377633087;1413.6324555615613;1444.8266208055102;1484.4730120807174;1487.6280929767559;1497.5657059452853;26790.928362866045;27514.724852751217;27767.44465110646;27851.721482784913;28141.57787633228;26290;35364;35377.1155721109;35711.64288357187;36039.63960360363;321001;327668.3616581902;327849;328075.7192428045;328174;24757.381065473506;30190.84904575551;30207.546886797154;30360.089197324116;31507.36985260236;42145.578544215394;42526.574734253496;42684.78657606692;42805.78597107119;42878;23055.654165187872;24138.51722965566;28730.569041463976;29197.708022919498;29938.850305748332;28683;29273.853630732563;29655.85172074126;29794.70205298006;30132.84933575318;34950.475742864386;35115;35591.46612800758;35730.64269357273;35801;28296.009639662512;29473.410531789672;29690.703092968793;29979.850100749358;30509.54235686511;15128.697426051638;20176.697349540063;23480.06079756859;24154.75845241525;24408.63387049231 16:20:19 INFO - PID 3035 | |2;jquery.html;417.4760675352432;417.5623490022257;428.6656408001759;441.5253602377444;443.9778011099446;21793.455163620918;22054;22255.884176215342;22338.329850104525;22841.31476055719;21331;21913.890430547865;22024.669629955522;22312;23179.884100579515;4869.70781753096;6121.724522396481;6152.3847615238565;6338.968305158491;6350.84122896929;6088.512918966472;6274.7176377062915;6275.905861412082;6282.623042617458;6293.874122517547;6041.489627593101;6133.478654314388;6202.2867370252325;6226;6258.217722784652;5175.301334319894;6121.418465245794;6153.692315384235;6189.566730328879;6201.286852012031;5371;5407.134858422673;5436.945630543723;5487.42382049887;5504.944950550484;22630;26883.32791680204;26912.59631105535;27131.321716957038;27466;1755.6664233795666;1936.6223586400658;1943.2129987355145;1954.3452943264024;1964.6267209230343;16264.430744923871;16571.42000030005;16787.832121678748;17249.827501725074;17351;1601.6476375197437;1912.5601111744238;1922.6827573450364;1944.8346890514324;1950.317388913883;121518.17722734254;137193;143378;145600;146128;12109.152359334938;12468.254444377795;12469.438875250635;12499.125061245626;12591.622251332476;15984.520464386087;15990.520284391487;15992;16006.679866402606;16045.759313610306;8787.077356877564;9017.63929442828;9037.954810226038;9132.771680707903;9191;8524.360672949553;8629.395942284105;8646.221840034426;8657.004444488965;8794.824103517894;7818.296353328228;7928.326092282221;7964.761057168294;7970.960145199353;7983.2415920487465;1961.460598335469;2142.1324363632752;2224.1103558576374;2235.7540670526228;2418.286605451396;915.9358844880927;923.2844545477175;932.7388331267256;937.1659223291276;988.4761076629397;18515.537111572317;20947.057382417523;20947.581048378946;21003.684944725843;21332.786672133392;874.5452364770412;967.1682353176403;972.5040229482873;1005.9949700251598;1010.1767059846202;9437.764055898657;9873.703788886345;10803.297785643908;10872.967068128515;10936.289141205796;630615;633560;640228;645155;650284;142325;142909;148649;150148.249258753;153310;1115.3642423818508;1158.2471393594062;1199.964001079969;1256.0830593666674;1256.5664845628244;16552.83447165561;20890.373288801362;21053.263135790174;21076.789232108094;21298.36104916855;42317.78841105775;46317;48121.7969550764;59119.70440147772;60448.39551604428;69138.65430672815;82716;83544.58227708822;85184;85357;6935.583864968118;8138.511689298661;8597.52713600758;8613.65545378178;8874.201321881063;13754.381052852446;13764.65588360299;13891.583252502442;14033.929830351191;14069.718605627626;9792.265580081379;10830.945845270724;11718.472668730099;11856.22934509254;11860.644180674593;10751.301165424535;10824.5128969195;10837.67486975392;10991.296349065908;11021.614243501439;10162.237832162766;11624.767504650028;11702.180847340946;11941.820872686743;12059.733727958788;6588.8352791180605;8431.620577073923;8723.52020638871;8761.211490965588;8918.573028315697;11277.492512836778;11695.181337306307;11918.642440726791;12036;12072.275663460221 16:20:19 INFO - PID 3035 | |3;mootools.html;1234.7283597608528;1252.8935040521555;1301.7786976214043;1496.4313560846876;1499.745043342632;10678.679639610804;10982.450877456135;11144.721381965455;11152.553897844076;11331.773364532704;10921.399323037229;11066.833997490045;11098.889011109886;11110.16673749468;11122.6107086252;2463.618139188429;2471.0239455415144;2474.3690358958443;2483.205374280222;2483.217786397288;2401.063585201764;2425.442148305891;2457.041753716052;2458.9016439342495;2473.764992325735;2184.6891864881104;2243.540074284768;2375.6555299481565;2420.7337192908767;2439.9634005489934;1989.2242025610024;2341.290857673908;2408.843425177358;2411.131992482705;2444.828861979662;2339.8245131615026;2382.5234953009467;2392.377981724747;2405.56699794037;2443.3769388805918;9153.855768028996;11080.224384293102;11203.21577489576;11206.383648899307;11212.831807522894;5029.270755740434;6127.87744245117;6302.590331628429;6370.71331790066;6381.010943303795;3950.6641935435523;4022.839086436539;4037.1723796621623;4058.6955978301744;4115.032967252693;1203.3261373630794;1391.304347826087;1518.574799056255;1597.9840201598067;1600.1039417926;13663;14104.435822567188;14767;14916.403343866232;15139.621509462351;4586.518415566317;4682.9531704683195;5496.450354964471;5595.38450770415;5717;12837;12855.871441285655;12896.29070401137;12901.354932253349;12920;6469.935300647027;6540.182477190351;6557.180352455943;6563.310852360433;6568.6058836469965;4279.87160385189;4763.595094416944;5863.589548731547;5914.645121292736;6051.788187413419;4206.348016057516;4233.5131459881895;4245.660347172219;4259.914801703949;4316.676249281317;1079.881213066562;1253.4923356040817;1258.389681004712;1274.866139055386;1275.7831168701343;743.0674503498076;743.6579173580213;770.587735561472;772.9652165652559;800.1438460846954;6039.78860739872;6353.301136875032;6565;6599.967000164968;6603.867922641616;462.92130337841974;567.957403194762;584.894718950593;585.1047896718028;604.5284677951205;3874.767513949172;3923.7841918694744;3972;4030.778307193074;4066;13355.666108347175;13907;13916.512922047883;13926.234057126956;13943.58169254924;13239.536616218385;13657.658558535917;13724;13815.792763108562;13839.723205535833;9958.302918795614;10144.594216231415;10163.593456261668;10177.758454395844;10222.744431389276;4413.955860441354;6341;6644.534882558368;6672.165979252593;6680.899786503303;12071.698207544881;12183;12223.93888030554;12319.199252048587;12570.685732856751;12256.571020014257;12660.430280637209;12697;12716.809247861105;12751.681207969505;9072;9104;9157.954210228907;9265.397749146285;9274.211692006254;9988.001199880244;10033.648822291185;10136.898631013595;10156.035176658206;10168.33905796121;8481.236688698047;8683.131686831266;8697.173768491983;8719.34604904622;8737.737867863974;8464.619092140745;8523.318134549098;8554.18735220153;8566.614502347285;8624.620060790243;7648.73229436967;7813.218678132142;7823.726169584037;7825.608719564113;7826.5695386751995;4752.6197904168275;4891.755412229303;5646.3788983212635;5692.48767610917;5723.599348045597;5529.45087551289;5584.994700953867;5977.3424923257535;6090.147379366804;6124.14262003311 16:20:19 INFO - PID 3035 | |4;prototype.html;343.685527742116;379.62796459469735;388.828915277278;398.2453251089186;399.1518024198578;4772.904541909164;5178.6116041296955;6166.475849552782;6236.376362363767;6253.6560489173135;5001.1248031594505;5682.801101961432;6010.489108425789;6178.505719542437;6225.377462253772;3893.9805300973458;4376.956230437695;4597.816087356502;4752.453467851189;4756.85729428116;4733.745557427284;4737.194676904935;4742.834000809972;4756.310335001423;4762.285657151438;4746.5490778376;4748.406449193851;4759.333693282943;4769.713817170981;4806.255030470265;3888.6694630956404;3974.105826189113;4520.954790452102;4553.816007837953;4723.409573803275;3645.5260816094005;4171.249175148472;4176.412963074043;4187.225363307767;4193.412922190896;5268.736563171826;5286.392064912546;5464.289642346509;6118.510519158456;6128.846778830521;5311.548518375943;5572.244742905972;5594.440555944414;5598;5602.859928501779;5471.425500322449;6025.367336429656;6411.67941602918;6669.966155245945;6675.198976122896;4171.728837625575;4761.166950723996;4762.309380170024;4779.187538118528;4779.972305954202;5659.226978780409;6765.9661701691175;6941.327588878318;6998.615076170809;7010.964945175241;4615.23848564983;4631.652626052992;4633.2818413146015;4646.767661616973;4660.834791302174;3933.6459718624888;3954.7824869631872;4305.07440900208;4634.026854360554;4741.336212930192;4501.459824820977;5041.218611115283;5357.571394288448;5374.48942350476;5453.263809385761;5770.307563092372;6546.934530654633;6584.111144995364;6598.439132673777;6618.220347454081;1513.8343475523784;1675.748637704354;1686.9662606748043;1710.324421853361;1713.7857767779028;1138.9829152562718;1164.5109054197258;1164.7088227943013;1170.6839153428527;1183.384639987219;6147.569670123138;6179.227596550431;6198.473129783929;6211.254649442096;6250.218722659668;5612.17353076354;6057.637031667928;6541.247756507967;6837.897431538531;6844.965775171092;5687.175359572839;6080.270367555921;6145.9692701536205;6174.783882564178;6219.170277523715;3441.399749116673;3620.7646502977223;3635.8911831138407;3662.853485860589;3665.6700896919406;6249.96875515544;6713.69788359525;6902.240753517109;7039.54242974205;7080.575165490087;5964.970175149097;6916.446684265246;7032.331928466788;7049.004173226358;7058.823529411806;5437.755301011464;6724.798256052326;6894.207166175854;7000.22997470288;7062.293770622897;5369.295186321426;6057.787977420945;6832.248452670103;6871.690773915086;6883.518153729191;5111.693298402108;6663.8667226654225;6712.194536655633;6730.697118629576;6768.763093291711;5854.736536855767;6273.498120150286;6887.20797108339;7139.678714457758;7171.390431813355;4598.287265473857;4861.586765125004;4886.066716293418;4906.337644417958;4932.358793356841;6516.869662606816;6635.568688035262;6654.6025334678325;6889.689963951534;6961.269066748121;5440.53755430777;6013.30846952606;6703.396694297537;6718.428933540704;6751.898721519083;6319.494440444661;6640.738259730636;6697.933020669731;6830.624315662488;6967.442604591722;6551.83789349504;6573.769918052845;6575.013747937656;6585.901211481927;6590.80227593173;5465.945340546703;6670.733170673118;6700.49746269022;6705.329467053256;6723.075232510522;5646.689432081275;6104.633721976696;6497.166671663741;6518.934810651833;6524.771632993013 16:20:19 INFO - PID 3035 | |5;yui.html;714.2500374606661;716.9210338440646;740.7481456304859;746.8095635612918;751.7718570248629;15408.07551546908;15452.999999999985;15852.128132952697;16345.673086538278;16797.244124014418;15172.165530895796;15189.620259493518;15288.464903728373;15361.69276614467;15459.149746763924;962.2254085461218;990.128686755654;997.7455748784087;998.475800204891;999.6751055906859;964.5177411294353;986.0336869867534;998.6255154317131;999.7050869993333;1000.3597697473623;1059.89930956559;1061.6125114333252;1062.065382463435;1062.9946850265776;1064.3613831700957;1050.0759331788054;1051.747580580659;1052.3738375666467;1055.076807793177;1055.757175849555;769.3691173237947;778.9221077892222;779.7387875061861;781;783.9882401763978;16138.273777680033;16532.917335413247;16553.337866485326;16622.33510659572;16857.66284674312;14805.407783688639;14872.776908346383;14955.850441495555;15170.544883653509;15642.45251416195;11376.94311528437;11595.246308989888;11668.88331116695;11800.822987655192;12281.263124212486;3003.594514740504;3042.893498727534;3046.16230536602;3088.382323535302;3178.55500229968;78559;93508;97446.99999999929;97760;100176.49558761016;11307.547698091985;11548.422578871023;11754.11844111695;11843.881561184278;12013.939930300292;24906.252812415656;25195.992160313748;25234.747652523605;25236.87381563117;25349.61975570368;7836.804079898048;7999.36005119589;8037.638306276232;8128.349732021424;8207.343412526983;3449.4308439107767;4188.706790524634;4267.8292868284925;4268.103698223408;4418.536053714313;4077.918441631151;4123.113530590877;4140.4617399737845;4182.91634167315;4187.078842654611;737.9262073792685;742.9888551671729;751.7707099334646;753.4424525850831;755.4296506137831;535.2640119835227;540.9621326507186;571.3657839797821;576.4754073792828;604.3714536881682;9299.860502092473;9990;10145.797084058277;10155.492225388702;10249.923758005301;459.63688685938405;502.8692539939642;555.741580165217;597.1729155120103;606.7269728622138;4590.862274131781;4685.906281874343;4689.718616882998;4719.834805781781;4751.548602882721;98664;99050.51424228643;100474;101349.98650013554;103979;63423.04865427022;69807.65096174486;70833.93749093768;85646.57176714076;89887.55056224678;77277.22722772701;77417.61291193734;77704.61147694226;79082.6045869767;79898.4020319602;8339.95830020846;9183.954080229823;10093.596256149673;10533.736656583647;11139.33164010162;38025.049373765876;39727;40229.1954160921;40253.39619905762;40558.59441405548;46052.30921536247;57014;61493.38506614877;72305.63847180731;75623.62188189244;13446.663833404244;13747.518836841062;13995.370208340446;14010.779059255068;14472.421103155755;14756.328893921107;14862.331195096463;15011.474598389004;15174.165420901514;15482.767758483407;13638.795418068936;13660;14115.364808583434;14165.645858853612;14293;13616.485358138012;13812.447502099803;13829.377678004314;13991.510297139552;14185;13301.80047299272;13377.398017089059;13483.258420786951;13639.113457625223;14405.711885762435;8300.958495207486;8336.249737523409;8419.831603367777;8496;8988.505632190292;10054.396736195851;10080.949595251977;10706.357618542912;11215.439228038402;11270.549178032787 16:20:19 INFO - PID 3035 | __end_tp_report 16:20:19 INFO - PID 3035 | __start_cc_report 16:20:19 INFO - PID 3035 | _x_x_mozilla_cycle_collect,440 16:20:19 INFO - PID 3035 | __end_cc_report 16:20:19 INFO - PID 3035 | __startTimestamp1491261619309__endTimestamp 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | ------- Summary: start ------- 16:20:19 INFO - PID 3035 | Number of tests: 6 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | [#0] dojo.html Cycles:165 Average:6025.66 Median:6311.12 stddev:870.13 (13.8%) stddev-sans-first:850.45 16:20:19 INFO - PID 3035 | Values: 3527.6 3544.1 3554.0 3571.2 3581.4 5012.0 6064.8 6218.7 6231.0 6259.6 5538.1 6058.7 6063.5 6083.4 6151.9 5425.8 5451.9 5483.2 5492.7 5549.6 5525.7 5525.9 5539.0 5539.4 5543.3 234.2 4793.6 5319.0 5336.2 5348.4 4473.0 4605.8 5320.6 5419.5 5438.1 5392.3 5396.6 5398.3 5405.4 5470.0 4829.9 5733.2 6120.8 6170.6 6217.4 5508.6 6590.0 6595.7 6636.9 6639.9 6728.1 6730.3 6733.5 6760.4 6775.3 6107.8 6155.1 6171.0 6173.7 6228.3 6866.1 6899.6 6905.7 6914.4 6932.7 6502.6 6522.6 6555.8 6570.4 6574.6 5421.0 6292.1 6453.9 6469.5 6659.2 5522.3 6322.4 6493.6 6590.1 6602.2 6564.7 6570.2 6582.3 6582.4 6592.4 5259.3 5265.0 5296.9 5314.9 5363.3 4659.8 4684.6 4712.9 4764.2 4809.6 6274.3 6298.3 6311.1 6316.0 6322.8 6573.6 6684.1 6685.9 6688.2 6695.5 6150.0 6207.3 6228.0 6287.2 6289.9 5193.6 5840.0 5939.9 5961.1 5961.8 6818.3 6842.9 6861.3 6876.1 6879.5 6422.7 6491.0 6662.7 6719.6 6735.3 6718.5 6734.3 6831.2 6849.7 6851.9 6558.7 6576.5 6585.5 6586.2 6589.8 5068.8 6336.7 6691.7 6697.0 6697.4 6287.6 6386.3 6400.4 6407.7 6446.6 6471.4 6478.3 6480.5 6484.2 6488.8 6247.7 6276.9 6303.7 6321.4 6357.4 5483.0 6465.7 6472.9 6534.2 6538.6 6319.9 6330.2 6342.7 6360.1 6367.9 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | [#1] ext.html Cycles:180 Average:48680.20 Median:327962.36 stddev:94021.35 (28.7%) stddev-sans-first:94216.03 16:20:19 INFO - PID 3035 | Values: 680.5 737.4 823.4 1279.6 1291.4 37296.3 38493.4 38506.0 38513.4 38533.6 38316.0 38485.8 38575.4 38677.8 38831.0 8088.6 8255.9 8515.3 8733.9 8751.1 8603.6 8663.0 8706.2 8707.4 8724.0 8633.1 8636.8 8716.4 8721.2 8751.9 8652.2 8672.7 8677.3 8683.3 8691.0 7584.6 8055.7 8520.7 8528.9 8640.3 37597.6 37826.4 37879.6 38017.2 38023.0 2987.8 2996.5 2997.9 3007.8 3010.3 21338.0 28153.9 28339.7 28576.0 28682.4 2818.6 2839.5 2840.8 2898.3 2941.3 247770.0 296279.0 302126.0 302547.0 303504.0 39643.8 48252.0 48369.1 48488.8 48512.8 49900.0 49967.0 50297.0 50348.5 50366.0 14543.9 14652.0 14658.5 14670.5 14670.8 11886.8 11929.7 11932.6 11946.5 11950.0 12470.7 13272.5 13278.6 13334.7 13365.5 5879.9 5903.9 5914.9 5927.0 5976.4 961.7 966.9 971.9 972.4 1007.4 23861.6 24171.2 24230.2 24235.2 24306.5 995.7 1063.8 1068.6 1107.6 1120.3 12144.3 12273.7 12277.0 12332.6 12336.6 429128.0 432500.0 433094.0 434146.0 434497.0 51143.2 52812.9 52905.5 53196.7 53244.7 1413.6 1444.8 1484.5 1487.6 1497.6 26790.9 27514.7 27767.4 27851.7 28141.6 26290.0 35364.0 35377.1 35711.6 36039.6 321001.0 327668.4 327849.0 328075.7 328174.0 24757.4 30190.8 30207.5 30360.1 31507.4 42145.6 42526.6 42684.8 42805.8 42878.0 23055.7 24138.5 28730.6 29197.7 29938.9 28683.0 29273.9 29655.9 29794.7 30132.8 34950.5 35115.0 35591.5 35730.6 35801.0 28296.0 29473.4 29690.7 29979.9 30509.5 15128.7 20176.7 23480.1 24154.8 24408.6 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | [#2] jquery.html Cycles:180 Average:37955.03 Median:12432.88 stddev:107373.20 (863.6%) stddev-sans-first:107637.41 16:20:19 INFO - PID 3035 | Values: 417.5 417.6 428.7 441.5 444.0 21793.5 22054.0 22255.9 22338.3 22841.3 21331.0 21913.9 22024.7 22312.0 23179.9 4869.7 6121.7 6152.4 6339.0 6350.8 6088.5 6274.7 6275.9 6282.6 6293.9 6041.5 6133.5 6202.3 6226.0 6258.2 5175.3 6121.4 6153.7 6189.6 6201.3 5371.0 5407.1 5436.9 5487.4 5504.9 22630.0 26883.3 26912.6 27131.3 27466.0 1755.7 1936.6 1943.2 1954.3 1964.6 16264.4 16571.4 16787.8 17249.8 17351.0 1601.6 1912.6 1922.7 1944.8 1950.3 121518.2 137193.0 143378.0 145600.0 146128.0 12109.2 12468.3 12469.4 12499.1 12591.6 15984.5 15990.5 15992.0 16006.7 16045.8 8787.1 9017.6 9038.0 9132.8 9191.0 8524.4 8629.4 8646.2 8657.0 8794.8 7818.3 7928.3 7964.8 7971.0 7983.2 1961.5 2142.1 2224.1 2235.8 2418.3 915.9 923.3 932.7 937.2 988.5 18515.5 20947.1 20947.6 21003.7 21332.8 874.5 967.2 972.5 1006.0 1010.2 9437.8 9873.7 10803.3 10873.0 10936.3 630615.0 633560.0 640228.0 645155.0 650284.0 142325.0 142909.0 148649.0 150148.2 153310.0 1115.4 1158.2 1200.0 1256.1 1256.6 16552.8 20890.4 21053.3 21076.8 21298.4 42317.8 46317.0 48121.8 59119.7 60448.4 69138.7 82716.0 83544.6 85184.0 85357.0 6935.6 8138.5 8597.5 8613.7 8874.2 13754.4 13764.7 13891.6 14033.9 14069.7 9792.3 10830.9 11718.5 11856.2 11860.6 10751.3 10824.5 10837.7 10991.3 11021.6 10162.2 11624.8 11702.2 11941.8 12059.7 6588.8 8431.6 8723.5 8761.2 8918.6 11277.5 11695.2 11918.6 12036.0 12072.3 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | [#3] mootools.html Cycles:180 Average:6766.95 Median:2483.21 stddev:4226.56 (170.2%) stddev-sans-first:4217.97 16:20:19 INFO - PID 3035 | Values: 1234.7 1252.9 1301.8 1496.4 1499.7 10678.7 10982.5 11144.7 11152.6 11331.8 10921.4 11066.8 11098.9 11110.2 11122.6 2463.6 2471.0 2474.4 2483.2 2483.2 2401.1 2425.4 2457.0 2458.9 2473.8 2184.7 2243.5 2375.7 2420.7 2440.0 1989.2 2341.3 2408.8 2411.1 2444.8 2339.8 2382.5 2392.4 2405.6 2443.4 9153.9 11080.2 11203.2 11206.4 11212.8 5029.3 6127.9 6302.6 6370.7 6381.0 3950.7 4022.8 4037.2 4058.7 4115.0 1203.3 1391.3 1518.6 1598.0 1600.1 13663.0 14104.4 14767.0 14916.4 15139.6 4586.5 4683.0 5496.5 5595.4 5717.0 12837.0 12855.9 12896.3 12901.4 12920.0 6469.9 6540.2 6557.2 6563.3 6568.6 4279.9 4763.6 5863.6 5914.6 6051.8 4206.3 4233.5 4245.7 4259.9 4316.7 1079.9 1253.5 1258.4 1274.9 1275.8 743.1 743.7 770.6 773.0 800.1 6039.8 6353.3 6565.0 6600.0 6603.9 462.9 568.0 584.9 585.1 604.5 3874.8 3923.8 3972.0 4030.8 4066.0 13355.7 13907.0 13916.5 13926.2 13943.6 13239.5 13657.7 13724.0 13815.8 13839.7 9958.3 10144.6 10163.6 10177.8 10222.7 4414.0 6341.0 6644.5 6672.2 6680.9 12071.7 12183.0 12223.9 12319.2 12570.7 12256.6 12660.4 12697.0 12716.8 12751.7 9072.0 9104.0 9158.0 9265.4 9274.2 9988.0 10033.6 10136.9 10156.0 10168.3 8481.2 8683.1 8697.2 8719.3 8737.7 8464.6 8523.3 8554.2 8566.6 8624.6 7648.7 7813.2 7823.7 7825.6 7826.6 4752.6 4891.8 5646.4 5692.5 5723.6 5529.5 5585.0 5977.3 6090.1 6124.1 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | [#4] prototype.html Cycles:180 Average:5339.88 Median:5684.99 stddev:1623.87 (28.6%) stddev-sans-first:1584.54 16:20:19 INFO - PID 3035 | Values: 343.7 379.6 388.8 398.2 399.2 4772.9 5178.6 6166.5 6236.4 6253.7 5001.1 5682.8 6010.5 6178.5 6225.4 3894.0 4377.0 4597.8 4752.5 4756.9 4733.7 4737.2 4742.8 4756.3 4762.3 4746.5 4748.4 4759.3 4769.7 4806.3 3888.7 3974.1 4521.0 4553.8 4723.4 3645.5 4171.2 4176.4 4187.2 4193.4 5268.7 5286.4 5464.3 6118.5 6128.8 5311.5 5572.2 5594.4 5598.0 5602.9 5471.4 6025.4 6411.7 6670.0 6675.2 4171.7 4761.2 4762.3 4779.2 4780.0 5659.2 6766.0 6941.3 6998.6 7011.0 4615.2 4631.7 4633.3 4646.8 4660.8 3933.6 3954.8 4305.1 4634.0 4741.3 4501.5 5041.2 5357.6 5374.5 5453.3 5770.3 6546.9 6584.1 6598.4 6618.2 1513.8 1675.7 1687.0 1710.3 1713.8 1139.0 1164.5 1164.7 1170.7 1183.4 6147.6 6179.2 6198.5 6211.3 6250.2 5612.2 6057.6 6541.2 6837.9 6845.0 5687.2 6080.3 6146.0 6174.8 6219.2 3441.4 3620.8 3635.9 3662.9 3665.7 6250.0 6713.7 6902.2 7039.5 7080.6 5965.0 6916.4 7032.3 7049.0 7058.8 5437.8 6724.8 6894.2 7000.2 7062.3 5369.3 6057.8 6832.2 6871.7 6883.5 5111.7 6663.9 6712.2 6730.7 6768.8 5854.7 6273.5 6887.2 7139.7 7171.4 4598.3 4861.6 4886.1 4906.3 4932.4 6516.9 6635.6 6654.6 6889.7 6961.3 5440.5 6013.3 6703.4 6718.4 6751.9 6319.5 6640.7 6697.9 6830.6 6967.4 6551.8 6573.8 6575.0 6585.9 6590.8 5465.9 6670.7 6700.5 6705.3 6723.1 5646.7 6104.6 6497.2 6518.9 6524.8 16:20:19 INFO - PID 3035 | 16:20:19 INFO - PID 3035 | [#5] yui.html Cycles:180 Average:19532.99 Median:3313.99 stddev:27063.95 (816.7%) stddev-sans-first:27102.98 16:20:19 INFO - PID 3035 | Values: 714.3 716.9 740.7 746.8 751.8 15408.1 15453.0 15852.1 16345.7 16797.2 15172.2 15189.6 15288.5 15361.7 15459.1 962.2 990.1 997.7 998.5 999.7 964.5 986.0 998.6 999.7 1000.4 1059.9 1061.6 1062.1 1063.0 1064.4 1050.1 1051.7 1052.4 1055.1 1055.8 769.4 778.9 779.7 781.0 784.0 16138.3 16532.9 16553.3 16622.3 16857.7 14805.4 14872.8 14955.9 15170.5 15642.5 11376.9 11595.2 11668.9 11800.8 12281.3 3003.6 3042.9 3046.2 3088.4 3178.6 78559.0 93508.0 97447.0 97760.0 100176.5 11307.5 11548.4 11754.1 11843.9 12013.9 24906.3 25196.0 25234.7 25236.9 25349.6 7836.8 7999.4 8037.6 8128.3 8207.3 3449.4 4188.7 4267.8 4268.1 4418.5 4077.9 4123.1 4140.5 4182.9 4187.1 737.9 743.0 751.8 753.4 755.4 535.3 541.0 571.4 576.5 604.4 9299.9 9990.0 10145.8 10155.5 10249.9 459.6 502.9 555.7 597.2 606.7 4590.9 4685.9 4689.7 4719.8 4751.5 98664.0 99050.5 100474.0 101350.0 103979.0 63423.0 69807.7 70833.9 85646.6 89887.6 77277.2 77417.6 77704.6 79082.6 79898.4 8340.0 9184.0 10093.6 10533.7 11139.3 38025.0 39727.0 40229.2 40253.4 40558.6 46052.3 57014.0 61493.4 72305.6 75623.6 13446.7 13747.5 13995.4 14010.8 14472.4 14756.3 14862.3 15011.5 15174.2 15482.8 13638.8 13660.0 14115.4 14165.6 14293.0 13616.5 13812.4 13829.4 13991.5 14185.0 13301.8 13377.4 13483.3 13639.1 14405.7 8301.0 8336.2 8419.8 8496.0 8988.5 10054.4 10080.9 10706.4 11215.4 11270.5 16:20:19 INFO - PID 3035 | -------- Summary: end -------- 16:20:19 INFO - PID 3035 | 16:20:19 INFO - TEST-INFO | 3035: exit 0 16:20:19 INFO - TEST-OK | dromaeo_css | took 1116814ms 16:20:19 INFO - TEST-START | kraken 16:20:19 INFO - operating with platform_type : mac_ 16:20:19 INFO - Initialising browser for kraken test... 16:20:19 INFO - TEST-INFO | started process 3040 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpkEimd2/profile http://localhost:53302/getInfo.html) 16:20:22 INFO - PID 3040 | 2017-04-03 16:20:22.774 plugin-container[3041:24254] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 16:20:22 INFO - PID 3040 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:22 INFO - PID 3040 | 2017-04-03 16:20:22.777 plugin-container[3041:24254] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a33, name = 'com.apple.CFPasteboardClient' 16:20:22 INFO - PID 3040 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:22 INFO - PID 3040 | 2017-04-03 16:20:22.777 plugin-container[3041:24254] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:22 INFO - PID 3040 | __metrics Screen width/height:1600/1200 16:20:22 INFO - PID 3040 | colorDepth:24 16:20:22 INFO - PID 3040 | Browser inner width/height: 1024/689 16:20:22 INFO - PID 3040 | __metrics 16:20:22 ERROR - PID 3040 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:20:23 INFO - TEST-INFO | 3040: exit 0 16:20:23 INFO - Browser initialized. 16:20:23 INFO - Running cycle 1/1 for kraken test... 16:20:23 INFO - TEST-INFO | started process 3042 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpkEimd2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:25 INFO - PID 3042 | 2017-04-03 16:20:25.975 plugin-container[3043:24377] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 16:20:25 INFO - PID 3042 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:25 INFO - PID 3042 | 2017-04-03 16:20:25.975 plugin-container[3043:24377] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 16:20:25 INFO - PID 3042 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:25 INFO - PID 3042 | 2017-04-03 16:20:25.975 plugin-container[3043:24377] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:35 INFO - PID 3042 | RSS: Main: 229572608 16:20:35 INFO - PID 3042 | 16:20:38 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53302/tests/kraken/driver.html?testName=audio-beat-detection) 16:20:39 INFO - PID 3042 | RSS: Main: 243200000 16:20:39 INFO - PID 3042 | 16:20:42 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53302/tests/kraken/driver.html?testName=audio-dft) 16:20:43 INFO - PID 3042 | RSS: Main: 251772928 16:20:43 INFO - PID 3042 | 16:20:46 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53302/tests/kraken/driver.html?testName=audio-fft) 16:20:47 INFO - PID 3042 | RSS: Main: 253992960 16:20:47 INFO - PID 3042 | 16:20:49 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53302/tests/kraken/driver.html?testName=audio-oscillator) 16:20:50 INFO - PID 3042 | RSS: Main: 255975424 16:20:50 INFO - PID 3042 | 16:20:52 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53302/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:52 INFO - PID 3042 | RSS: Main: 256618496 16:20:52 INFO - PID 3042 | 16:20:55 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53302/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:56 INFO - PID 3042 | RSS: Main: 262381568 16:20:56 INFO - PID 3042 | 16:20:59 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53302/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:00 INFO - PID 3042 | RSS: Main: 263000064 16:21:00 INFO - PID 3042 | 16:21:03 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53302/tests/kraken/driver.html?testName=json-parse-financial) 16:21:04 INFO - PID 3042 | RSS: Main: 262938624 16:21:04 INFO - PID 3042 | 16:21:05 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53302/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:06 INFO - PID 3042 | RSS: Main: 262045696 16:21:06 INFO - PID 3042 | 16:21:08 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:08 INFO - PID 3042 | RSS: Main: 262004736 16:21:08 INFO - PID 3042 | 16:21:10 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:11 INFO - PID 3042 | RSS: Main: 262033408 16:21:11 INFO - PID 3042 | 16:21:14 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:15 INFO - PID 3042 | RSS: Main: 262414336 16:21:15 INFO - PID 3042 | 16:21:18 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:19 INFO - PID 3042 | RSS: Main: 259715072 16:21:19 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | Cycle 1(1): loaded http://localhost:53302/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53302/tests/kraken/driver.html?testName=ai-astar) 16:21:21 INFO - PID 3042 | RSS: Main: 262995968 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | __start_tp_report 16:21:21 INFO - PID 3042 | _x_x_mozilla_page_load 16:21:21 INFO - PID 3042 | _x_x_mozilla_page_load_details 16:21:21 INFO - PID 3042 | |i|pagename|runs| 16:21:21 INFO - PID 3042 | |0;ai-astar;111;123;133;185;157;122;126;123;138;179 16:21:21 INFO - PID 3042 | |1;audio-beat-detection;154;119;124;203;120;120;107;110;137;117 16:21:21 INFO - PID 3042 | |2;audio-dft;217;230;233;260;267;218;217;245;271;219 16:21:21 INFO - PID 3042 | |3;audio-fft;87;62;91;123;91;71;61;61;77;62 16:21:21 INFO - PID 3042 | |4;audio-oscillator;95;70;90;72;73;75;84;108;56;90 16:21:21 INFO - PID 3042 | |5;imaging-gaussian-blur;94;114;104;96;95;95;95;94;94;94 16:21:21 INFO - PID 3042 | |6;imaging-darkroom;104;103;102;103;103;104;102;106;101;142 16:21:21 INFO - PID 3042 | |7;imaging-desaturate;103;104;105;105;105;101;88;137;162;127 16:21:21 INFO - PID 3042 | |8;json-parse-financial;53;53;53;54;55;55;57;55;54;54 16:21:21 INFO - PID 3042 | |9;json-stringify-tinderbox;62;66;60;71;65;60;61;62;62;62 16:21:21 INFO - PID 3042 | |10;stanford-crypto-aes;86;90;87;89;89;87;90;88;85;92 16:21:21 INFO - PID 3042 | |11;stanford-crypto-ccm;149;148;150;148;163;139;160;167;275;175 16:21:21 INFO - PID 3042 | |12;stanford-crypto-pbkdf2;204;202;201;225;262;203;200;204;197;200 16:21:21 INFO - PID 3042 | |13;stanford-crypto-sha256-iterative;74;71;73;73;92;104;62;89;107;107 16:21:21 INFO - PID 3042 | __end_tp_report 16:21:21 INFO - PID 3042 | __start_cc_report 16:21:21 INFO - PID 3042 | _x_x_mozilla_cycle_collect,1020 16:21:21 INFO - PID 3042 | __end_cc_report 16:21:21 INFO - PID 3042 | __startTimestamp1491261681743__endTimestamp 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | ------- Summary: start ------- 16:21:21 INFO - PID 3042 | Number of tests: 14 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#0] ai-astar Cycles:10 Average:139.70 Median:135.50 stddev:25.44 (18.8%) stddev-sans-first:24.78 16:21:21 INFO - PID 3042 | Values: 111.0 123.0 133.0 185.0 157.0 122.0 126.0 123.0 138.0 179.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:122.00 stddev:28.67 (23.5%) stddev-sans-first:29.19 16:21:21 INFO - PID 3042 | Values: 154.0 119.0 124.0 203.0 120.0 120.0 107.0 110.0 137.0 117.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#2] audio-dft Cycles:10 Average:237.70 Median:239.00 stddev:21.59 (9.0%) stddev-sans-first:21.56 16:21:21 INFO - PID 3042 | Values: 217.0 230.0 233.0 260.0 267.0 218.0 217.0 245.0 271.0 219.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#3] audio-fft Cycles:10 Average:78.60 Median:74.00 stddev:19.95 (27.0%) stddev-sans-first:20.92 16:21:21 INFO - PID 3042 | Values: 87.0 62.0 91.0 123.0 91.0 71.0 61.0 61.0 77.0 62.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:79.50 stddev:14.99 (18.9%) stddev-sans-first:15.06 16:21:21 INFO - PID 3042 | Values: 95.0 70.0 90.0 72.0 73.0 75.0 84.0 108.0 56.0 90.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:94.50 stddev:6.54 (6.9%) stddev-sans-first:6.81 16:21:21 INFO - PID 3042 | Values: 94.0 114.0 104.0 96.0 95.0 95.0 95.0 94.0 94.0 94.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#6] imaging-darkroom Cycles:10 Average:107.00 Median:103.50 stddev:12.37 (12.0%) stddev-sans-first:13.08 16:21:21 INFO - PID 3042 | Values: 104.0 103.0 102.0 103.0 103.0 104.0 102.0 106.0 101.0 142.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#7] imaging-desaturate Cycles:10 Average:113.70 Median:116.00 stddev:21.88 (18.9%) stddev-sans-first:22.87 16:21:21 INFO - PID 3042 | Values: 103.0 104.0 105.0 105.0 105.0 101.0 88.0 137.0 162.0 127.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:1.25 (2.3%) stddev-sans-first:1.24 16:21:21 INFO - PID 3042 | Values: 53.0 53.0 53.0 54.0 55.0 55.0 57.0 55.0 54.0 54.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:62.00 stddev:3.38 (5.5%) stddev-sans-first:3.56 16:21:21 INFO - PID 3042 | Values: 62.0 66.0 60.0 71.0 65.0 60.0 61.0 62.0 62.0 62.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:89.00 stddev:2.11 (2.4%) stddev-sans-first:2.07 16:21:21 INFO - PID 3042 | Values: 86.0 90.0 87.0 89.0 89.0 87.0 90.0 88.0 85.0 92.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#11] stanford-crypto-ccm Cycles:10 Average:167.40 Median:161.50 stddev:39.31 (24.3%) stddev-sans-first:41.13 16:21:21 INFO - PID 3042 | Values: 149.0 148.0 150.0 148.0 163.0 139.0 160.0 167.0 275.0 175.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.80 Median:203.50 stddev:19.90 (9.8%) stddev-sans-first:20.99 16:21:21 INFO - PID 3042 | Values: 204.0 202.0 201.0 225.0 262.0 203.0 200.0 204.0 197.0 200.0 16:21:21 INFO - PID 3042 | 16:21:21 INFO - PID 3042 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.20 Median:73.00 stddev:16.76 (23.0%) stddev-sans-first:17.28 16:21:21 INFO - PID 3042 | Values: 74.0 71.0 73.0 73.0 92.0 104.0 62.0 89.0 107.0 107.0 16:21:21 INFO - PID 3042 | -------- Summary: end -------- 16:21:21 INFO - PID 3042 | 16:21:22 INFO - TEST-INFO | 3042: exit 0 16:21:22 INFO - TEST-OK | kraken | took 62765ms 16:21:22 INFO - SUITE-END | took 1179s 16:21:22 INFO - Completed test suite (00:19:39) 16:21:22 INFO - javascript benchmark 16:21:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7637.214287558244, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3527.6472352764727, 3544.0785395797093, 3553.9822300888522, 3571.2321850802073, 3581.355356035914, 5011.974940125298, 6064.818055458332, 6218.657973811439, 6230.968845155773, 6259.593126446789, 5538.113901775717, 6058.697065146747, 6063.514918806496, 6083.422074902888, 6151.8769624607485, 5425.782968681248, 5451.918221226685, 5483.232347471358, 5492.725363731817, 5549.556035517149, 5525.696086715229, 5525.944740552603, 5539.0, 5539.362973258067, 5543.25166102575, 234.22545091371714, 4793.616510679138, 5318.962802253562, 5336.199570064483, 5348.411674715778, 4473.0, 4605.825514493816, 5320.6275560710765, 5419.485148910847, 5438.129899216106, 5392.325959255093, 5396.568274538027, 5398.2712333834825, 5405.351357837046, 5469.988052210314, 4829.903401931977, 5733.16869053989, 6120.785772497986, 6170.598911070766, 6217.440430361289, 5508.55931525477, 6589.967050164766, 6595.703193356262, 6636.900446493307, 6639.86720265592, 6728.091707619457, 6730.326967303279, 6733.49498787588, 6760.391564759196, 6775.254721980578, 6107.7957376609465, 6155.138280640714, 6171.012637977902, 6173.660448675366, 6228.314885362606, 6866.141732283465, 6899.551529150589, 6905.654717264217, 6914.446844252448, 6932.688029038705, 6502.60984340941, 6522.576032557963, 6555.770548030796, 6570.375814297634, 6574.572652777554, 5421.0, 6292.119103325538, 6453.9354606453335, 6469.482441404771, 6659.167604049494, 5522.30971128609, 6322.367763223733, 6493.610383377012, 6590.143281373391, 6602.207735071822, 6564.67176641166, 6570.211574611078, 6582.308857569982, 6582.3746744059235, 6592.439642630334, 5259.289995850588, 5265.0, 5296.867578310496, 5314.867128321823, 5363.2759577456545, 4659.836905708352, 4684.5549672781035, 4712.9057418851435, 4764.237721964469, 4809.591184749265, 6274.309825919146, 6298.275698294755, 6311.116443697886, 6316.0, 6322.810315690536, 6573.638449885207, 6684.064231007663, 6685.899711504331, 6688.230853451915, 6695.46436285096, 6150.04674275488, 6207.317195108534, 6228.03465462854, 6287.245530536365, 6289.937100629027, 5193.636445448782, 5840.036393995028, 5939.910901336483, 5961.076033214858, 5961.791337303173, 6818.318168183043, 6842.863142737117, 6861.348171923659, 6876.071730316543, 6879.484038697017, 6422.743090276336, 6490.967545162244, 6662.700178492077, 6719.596824190564, 6735.2927942567285, 6718.529702920749, 6734.292899245509, 6831.2144103428745, 6849.657517124224, 6851.931480685129, 6558.672066396565, 6576.473882089325, 6585.506087043395, 6586.209654841449, 6589.769358072444, 5068.771905264198, 6336.683165841781, 6691.665416729242, 6696.966515167393, 6697.430718388797, 6287.559870808999, 6386.297507274103, 6400.39196276353, 6407.743690252338, 6446.613203207822, 6471.385218404244, 6478.319776423407, 6480.513961453004, 6484.1894763154605, 6488.837779055561, 6247.718852651732, 6276.905846412216, 6303.6532990685955, 6321.399467050623, 6357.4278314950025, 5483.0, 6465.676716164079, 6472.8705425890275, 6534.248561415498, 6538.607683539003, 6319.936800631935, 6330.177076979963, 6342.714577843916, 6360.077788720517, 6367.885620016571], "value": 5969.7219889075495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.4522359500602, 737.4469148138896, 823.4071468542649, 1279.60332296988, 1291.4124073546532, 37296.254074918485, 38493.42259866104, 38506.0, 38513.42229866551, 38533.614663853354, 38316.0, 38485.807570962104, 38575.421368679425, 38677.80661096691, 38831.0, 8088.555129467863, 8255.917440825575, 8515.318774498026, 8733.868991965093, 8751.081136480674, 8603.612837422299, 8663.0, 8706.216440520351, 8707.43401678892, 8723.95638021809, 8633.13668633138, 8636.827263454727, 8716.389852710312, 8721.215090641841, 8751.912480875173, 8652.178043085896, 8672.739817805475, 8677.349198810052, 8683.305335573139, 8690.956545217297, 7584.582847943361, 8055.718049868226, 8520.701775437892, 8528.872066918939, 8640.30877529796, 37597.62402375969, 37826.43260351097, 37879.62120378789, 38017.239655206744, 38023.0, 2987.775916806248, 2996.5205567109156, 2997.9457968224287, 3007.804492707967, 3010.277533391992, 21338.0, 28153.85923070392, 28339.716602833912, 28576.0, 28682.426351473063, 2818.6053952446673, 2839.5172820620546, 2840.7727381809405, 2898.3478717288654, 2941.3087924337765, 247769.9999999982, 296279.0, 302126.0, 302547.0, 303504.0, 39643.80178099149, 48252.0, 48369.06523739011, 48488.7575562127, 48512.757436212596, 49900.0, 49967.000659986596, 50296.994060118595, 50348.49651503438, 50366.0, 14543.927280363741, 14651.974361794786, 14658.486952956595, 14670.486532971296, 14670.779938300933, 11886.762264754829, 11929.701757456132, 11932.642020739391, 11946.462409191434, 11949.98425133856, 12470.688232794071, 13272.469101236036, 13278.601641950758, 13334.733305333839, 13365.529791722904, 5879.911801322984, 5903.881922361615, 5914.85245280387, 5926.970365148233, 5976.372480889531, 961.7451375385529, 966.878421031614, 971.8882328532168, 972.4214092614883, 1007.4106647611083, 23861.642075368884, 24171.154009609578, 24230.213525444447, 24235.151769688327, 24306.513869722505, 995.711243739322, 1063.8244689626124, 1068.58859339155, 1107.5680484611007, 1120.2942146447685, 12144.271343719229, 12273.69315767113, 12276.956458700932, 12332.568360107352, 12336.629901102802, 429128.0, 432500.0, 433094.0, 434146.0, 434497.0, 51143.23285150726, 52812.94374112496, 52905.47094529006, 53196.73401632967, 53244.73377633087, 1413.6324555615613, 1444.8266208055102, 1484.4730120807174, 1487.6280929767559, 1497.5657059452853, 26790.928362866045, 27514.724852751217, 27767.44465110646, 27851.721482784913, 28141.57787633228, 26290.0, 35364.0, 35377.1155721109, 35711.64288357187, 36039.63960360363, 321001.0, 327668.3616581902, 327849.0, 328075.7192428045, 328174.0, 24757.381065473506, 30190.84904575551, 30207.546886797154, 30360.089197324116, 31507.36985260236, 42145.578544215394, 42526.574734253496, 42684.78657606692, 42805.78597107119, 42878.0, 23055.654165187872, 24138.51722965566, 28730.569041463976, 29197.708022919498, 29938.850305748332, 28683.0, 29273.853630732563, 29655.85172074126, 29794.70205298006, 30132.84933575318, 34950.475742864386, 35115.0, 35591.46612800758, 35730.64269357273, 35801.0, 28296.009639662512, 29473.410531789672, 29690.703092968793, 29979.850100749358, 30509.54235686511, 15128.697426051638, 20176.697349540063, 23480.06079756859, 24154.75845241525, 24408.63387049231], "value": 17306.94561937153, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.4760675352432, 417.5623490022257, 428.6656408001759, 441.5253602377444, 443.9778011099446, 21793.455163620918, 22054.0, 22255.884176215342, 22338.329850104525, 22841.31476055719, 21331.0, 21913.890430547865, 22024.669629955522, 22312.0, 23179.884100579515, 4869.70781753096, 6121.724522396481, 6152.3847615238565, 6338.968305158491, 6350.84122896929, 6088.512918966472, 6274.7176377062915, 6275.905861412082, 6282.623042617458, 6293.874122517547, 6041.489627593101, 6133.478654314388, 6202.2867370252325, 6226.0, 6258.217722784652, 5175.301334319894, 6121.418465245794, 6153.692315384235, 6189.566730328879, 6201.286852012031, 5371.0, 5407.134858422673, 5436.945630543723, 5487.42382049887, 5504.944950550484, 22630.0, 26883.32791680204, 26912.59631105535, 27131.321716957038, 27466.0, 1755.6664233795666, 1936.6223586400658, 1943.2129987355145, 1954.3452943264024, 1964.6267209230343, 16264.430744923871, 16571.42000030005, 16787.832121678748, 17249.827501725074, 17351.0, 1601.6476375197437, 1912.5601111744238, 1922.6827573450364, 1944.8346890514324, 1950.317388913883, 121518.17722734254, 137193.0, 143378.0, 145600.0, 146128.0, 12109.152359334938, 12468.254444377795, 12469.438875250635, 12499.125061245626, 12591.622251332476, 15984.520464386087, 15990.520284391487, 15992.0, 16006.679866402606, 16045.759313610306, 8787.077356877564, 9017.63929442828, 9037.954810226038, 9132.771680707903, 9191.0, 8524.360672949553, 8629.395942284105, 8646.221840034426, 8657.004444488965, 8794.824103517894, 7818.296353328228, 7928.326092282221, 7964.761057168294, 7970.960145199353, 7983.2415920487465, 1961.460598335469, 2142.1324363632752, 2224.1103558576374, 2235.7540670526228, 2418.286605451396, 915.9358844880927, 923.2844545477175, 932.7388331267256, 937.1659223291276, 988.4761076629397, 18515.537111572317, 20947.057382417523, 20947.581048378946, 21003.684944725843, 21332.786672133392, 874.5452364770412, 967.1682353176403, 972.5040229482873, 1005.9949700251598, 1010.1767059846202, 9437.764055898657, 9873.703788886345, 10803.297785643908, 10872.967068128515, 10936.289141205796, 630615.0, 633560.0, 640228.0, 645155.0, 650284.0, 142325.0, 142909.0, 148649.0, 150148.249258753, 153310.0, 1115.3642423818508, 1158.2471393594062, 1199.964001079969, 1256.0830593666674, 1256.5664845628244, 16552.83447165561, 20890.373288801362, 21053.263135790174, 21076.789232108094, 21298.36104916855, 42317.78841105775, 46317.0, 48121.7969550764, 59119.70440147772, 60448.39551604428, 69138.65430672815, 82716.0, 83544.58227708822, 85184.0, 85357.0, 6935.583864968118, 8138.511689298661, 8597.52713600758, 8613.65545378178, 8874.201321881063, 13754.381052852446, 13764.65588360299, 13891.583252502442, 14033.929830351191, 14069.718605627626, 9792.265580081379, 10830.945845270724, 11718.472668730099, 11856.22934509254, 11860.644180674593, 10751.301165424535, 10824.5128969195, 10837.67486975392, 10991.296349065908, 11021.614243501439, 10162.237832162766, 11624.767504650028, 11702.180847340946, 11941.820872686743, 12059.733727958788, 6588.8352791180605, 8431.620577073923, 8723.52020638871, 8761.211490965588, 8918.573028315697, 11277.492512836778, 11695.181337306307, 11918.642440726791, 12036.0, 12072.275663460221], "value": 10281.465293537654, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1234.7283597608528, 1252.8935040521555, 1301.7786976214043, 1496.4313560846876, 1499.745043342632, 10678.679639610804, 10982.450877456135, 11144.721381965455, 11152.553897844076, 11331.773364532704, 10921.399323037229, 11066.833997490045, 11098.889011109886, 11110.16673749468, 11122.6107086252, 2463.618139188429, 2471.0239455415144, 2474.3690358958443, 2483.205374280222, 2483.217786397288, 2401.063585201764, 2425.442148305891, 2457.041753716052, 2458.9016439342495, 2473.764992325735, 2184.6891864881104, 2243.540074284768, 2375.6555299481565, 2420.7337192908767, 2439.9634005489934, 1989.2242025610024, 2341.290857673908, 2408.843425177358, 2411.131992482705, 2444.828861979662, 2339.8245131615026, 2382.5234953009467, 2392.377981724747, 2405.56699794037, 2443.3769388805918, 9153.855768028996, 11080.224384293102, 11203.21577489576, 11206.383648899307, 11212.831807522894, 5029.270755740434, 6127.87744245117, 6302.590331628429, 6370.71331790066, 6381.010943303795, 3950.6641935435523, 4022.839086436539, 4037.1723796621623, 4058.6955978301744, 4115.032967252693, 1203.3261373630794, 1391.304347826087, 1518.574799056255, 1597.9840201598067, 1600.1039417926, 13663.0, 14104.435822567188, 14767.0, 14916.403343866232, 15139.621509462351, 4586.518415566317, 4682.9531704683195, 5496.450354964471, 5595.38450770415, 5717.0, 12837.0, 12855.871441285655, 12896.29070401137, 12901.354932253349, 12920.0, 6469.935300647027, 6540.182477190351, 6557.180352455943, 6563.310852360433, 6568.6058836469965, 4279.87160385189, 4763.595094416944, 5863.589548731547, 5914.645121292736, 6051.788187413419, 4206.348016057516, 4233.5131459881895, 4245.660347172219, 4259.914801703949, 4316.676249281317, 1079.881213066562, 1253.4923356040817, 1258.389681004712, 1274.866139055386, 1275.7831168701343, 743.0674503498076, 743.6579173580213, 770.587735561472, 772.9652165652559, 800.1438460846954, 6039.78860739872, 6353.301136875032, 6565.0, 6599.967000164968, 6603.867922641616, 462.92130337841974, 567.957403194762, 584.894718950593, 585.1047896718028, 604.5284677951205, 3874.767513949172, 3923.7841918694744, 3972.0, 4030.778307193074, 4066.0, 13355.666108347175, 13907.0, 13916.512922047883, 13926.234057126956, 13943.58169254924, 13239.536616218385, 13657.658558535917, 13724.0, 13815.792763108562, 13839.723205535833, 9958.302918795614, 10144.594216231415, 10163.593456261668, 10177.758454395844, 10222.744431389276, 4413.955860441354, 6341.0, 6644.534882558368, 6672.165979252593, 6680.899786503303, 12071.698207544881, 12183.0, 12223.93888030554, 12319.199252048587, 12570.685732856751, 12256.571020014257, 12660.430280637209, 12697.0, 12716.809247861105, 12751.681207969505, 9072.0, 9104.0, 9157.954210228907, 9265.397749146285, 9274.211692006254, 9988.001199880244, 10033.648822291185, 10136.898631013595, 10156.035176658206, 10168.33905796121, 8481.236688698047, 8683.131686831266, 8697.173768491983, 8719.34604904622, 8737.737867863974, 8464.619092140745, 8523.318134549098, 8554.18735220153, 8566.614502347285, 8624.620060790243, 7648.73229436967, 7813.218678132142, 7823.726169584037, 7825.608719564113, 7826.5695386751995, 4752.6197904168275, 4891.755412229303, 5646.3788983212635, 5692.48767610917, 5723.599348045597, 5529.45087551289, 5584.994700953867, 5977.3424923257535, 6090.147379366804, 6124.14262003311], "value": 5101.603629194656, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.685527742116, 379.62796459469735, 388.828915277278, 398.2453251089186, 399.1518024198578, 4772.904541909164, 5178.6116041296955, 6166.475849552782, 6236.376362363767, 6253.6560489173135, 5001.1248031594505, 5682.801101961432, 6010.489108425789, 6178.505719542437, 6225.377462253772, 3893.9805300973458, 4376.956230437695, 4597.816087356502, 4752.453467851189, 4756.85729428116, 4733.745557427284, 4737.194676904935, 4742.834000809972, 4756.310335001423, 4762.285657151438, 4746.5490778376, 4748.406449193851, 4759.333693282943, 4769.713817170981, 4806.255030470265, 3888.6694630956404, 3974.105826189113, 4520.954790452102, 4553.816007837953, 4723.409573803275, 3645.5260816094005, 4171.249175148472, 4176.412963074043, 4187.225363307767, 4193.412922190896, 5268.736563171826, 5286.392064912546, 5464.289642346509, 6118.510519158456, 6128.846778830521, 5311.548518375943, 5572.244742905972, 5594.440555944414, 5598.0, 5602.859928501779, 5471.425500322449, 6025.367336429656, 6411.67941602918, 6669.966155245945, 6675.198976122896, 4171.728837625575, 4761.166950723996, 4762.309380170024, 4779.187538118528, 4779.972305954202, 5659.226978780409, 6765.9661701691175, 6941.327588878318, 6998.615076170809, 7010.964945175241, 4615.23848564983, 4631.652626052992, 4633.2818413146015, 4646.767661616973, 4660.834791302174, 3933.6459718624888, 3954.7824869631872, 4305.07440900208, 4634.026854360554, 4741.336212930192, 4501.459824820977, 5041.218611115283, 5357.571394288448, 5374.48942350476, 5453.263809385761, 5770.307563092372, 6546.934530654633, 6584.111144995364, 6598.439132673777, 6618.220347454081, 1513.8343475523784, 1675.748637704354, 1686.9662606748043, 1710.324421853361, 1713.7857767779028, 1138.9829152562718, 1164.5109054197258, 1164.7088227943013, 1170.6839153428527, 1183.384639987219, 6147.569670123138, 6179.227596550431, 6198.473129783929, 6211.254649442096, 6250.218722659668, 5612.17353076354, 6057.637031667928, 6541.247756507967, 6837.897431538531, 6844.965775171092, 5687.175359572839, 6080.270367555921, 6145.9692701536205, 6174.783882564178, 6219.170277523715, 3441.399749116673, 3620.7646502977223, 3635.8911831138407, 3662.853485860589, 3665.6700896919406, 6249.96875515544, 6713.69788359525, 6902.240753517109, 7039.54242974205, 7080.575165490087, 5964.970175149097, 6916.446684265246, 7032.331928466788, 7049.004173226358, 7058.823529411806, 5437.755301011464, 6724.798256052326, 6894.207166175854, 7000.22997470288, 7062.293770622897, 5369.295186321426, 6057.787977420945, 6832.248452670103, 6871.690773915086, 6883.518153729191, 5111.693298402108, 6663.8667226654225, 6712.194536655633, 6730.697118629576, 6768.763093291711, 5854.736536855767, 6273.498120150286, 6887.20797108339, 7139.678714457758, 7171.390431813355, 4598.287265473857, 4861.586765125004, 4886.066716293418, 4906.337644417958, 4932.358793356841, 6516.869662606816, 6635.568688035262, 6654.6025334678325, 6889.689963951534, 6961.269066748121, 5440.53755430777, 6013.30846952606, 6703.396694297537, 6718.428933540704, 6751.898721519083, 6319.494440444661, 6640.738259730636, 6697.933020669731, 6830.624315662488, 6967.442604591722, 6551.83789349504, 6573.769918052845, 6575.013747937656, 6585.901211481927, 6590.80227593173, 5465.945340546703, 6670.733170673118, 6700.49746269022, 6705.329467053256, 6723.075232510522, 5646.689432081275, 6104.633721976696, 6497.166671663741, 6518.934810651833, 6524.771632993013], "value": 4844.847371060036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.2500374606661, 716.9210338440646, 740.7481456304859, 746.8095635612918, 751.7718570248629, 15408.07551546908, 15452.999999999985, 15852.128132952697, 16345.673086538278, 16797.244124014418, 15172.165530895796, 15189.620259493518, 15288.464903728373, 15361.69276614467, 15459.149746763924, 962.2254085461218, 990.128686755654, 997.7455748784087, 998.475800204891, 999.6751055906859, 964.5177411294353, 986.0336869867534, 998.6255154317131, 999.7050869993333, 1000.3597697473623, 1059.89930956559, 1061.6125114333252, 1062.065382463435, 1062.9946850265776, 1064.3613831700957, 1050.0759331788054, 1051.747580580659, 1052.3738375666467, 1055.076807793177, 1055.757175849555, 769.3691173237947, 778.9221077892222, 779.7387875061861, 781.0, 783.9882401763978, 16138.273777680033, 16532.917335413247, 16553.337866485326, 16622.33510659572, 16857.66284674312, 14805.407783688639, 14872.776908346383, 14955.850441495555, 15170.544883653509, 15642.45251416195, 11376.94311528437, 11595.246308989888, 11668.88331116695, 11800.822987655192, 12281.263124212486, 3003.594514740504, 3042.893498727534, 3046.16230536602, 3088.382323535302, 3178.55500229968, 78559.0, 93508.0, 97446.99999999929, 97760.0, 100176.49558761016, 11307.547698091985, 11548.422578871023, 11754.11844111695, 11843.881561184278, 12013.939930300292, 24906.252812415656, 25195.992160313748, 25234.747652523605, 25236.87381563117, 25349.61975570368, 7836.804079898048, 7999.36005119589, 8037.638306276232, 8128.349732021424, 8207.343412526983, 3449.4308439107767, 4188.706790524634, 4267.8292868284925, 4268.103698223408, 4418.536053714313, 4077.918441631151, 4123.113530590877, 4140.4617399737845, 4182.91634167315, 4187.078842654611, 737.9262073792685, 742.9888551671729, 751.7707099334646, 753.4424525850831, 755.4296506137831, 535.2640119835227, 540.9621326507186, 571.3657839797821, 576.4754073792828, 604.3714536881682, 9299.860502092473, 9990.0, 10145.797084058277, 10155.492225388702, 10249.923758005301, 459.63688685938405, 502.8692539939642, 555.741580165217, 597.1729155120103, 606.7269728622138, 4590.862274131781, 4685.906281874343, 4689.718616882998, 4719.834805781781, 4751.548602882721, 98664.0, 99050.51424228643, 100474.0, 101349.98650013554, 103979.0, 63423.04865427022, 69807.65096174486, 70833.93749093768, 85646.57176714076, 89887.55056224678, 77277.22722772701, 77417.61291193734, 77704.61147694226, 79082.6045869767, 79898.4020319602, 8339.95830020846, 9183.954080229823, 10093.596256149673, 10533.736656583647, 11139.33164010162, 38025.049373765876, 39727.0, 40229.1954160921, 40253.39619905762, 40558.59441405548, 46052.30921536247, 57014.0, 61493.38506614877, 72305.63847180731, 75623.62188189244, 13446.663833404244, 13747.518836841062, 13995.370208340446, 14010.779059255068, 14472.421103155755, 14756.328893921107, 14862.331195096463, 15011.474598389004, 15174.165420901514, 15482.767758483407, 13638.795418068936, 13660.0, 14115.364808583434, 14165.645858853612, 14293.0, 13616.485358138012, 13812.447502099803, 13829.377678004314, 13991.510297139552, 14185.0, 13301.80047299272, 13377.398017089059, 13483.258420786951, 13639.113457625223, 14405.711885762435, 8300.958495207486, 8336.249737523409, 8419.831603367777, 8496.0, 8988.505632190292, 10054.396736195851, 10080.949595251977, 10706.357618542912, 11215.439228038402, 11270.549178032787], "value": 7557.225418641091, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1654.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 133.0, 185.0, 157.0, 122.0, 126.0, 123.0, 138.0, 179.0], "value": 139.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 124.0, 203.0, 120.0, 120.0, 107.0, 110.0, 137.0, 117.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 230.0, 233.0, 260.0, 267.0, 218.0, 217.0, 245.0, 271.0, 219.0], "value": 237.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 62.0, 91.0, 123.0, 91.0, 71.0, 61.0, 61.0, 77.0, 62.0], "value": 78.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 70.0, 90.0, 72.0, 73.0, 75.0, 84.0, 108.0, 56.0, 90.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 114.0, 104.0, 96.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 103.0, 102.0, 103.0, 103.0, 104.0, 102.0, 106.0, 101.0, 142.0], "value": 107.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 104.0, 105.0, 105.0, 105.0, 101.0, 88.0, 137.0, 162.0, 127.0], "value": 113.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 55.0, 55.0, 57.0, 55.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 60.0, 71.0, 65.0, 60.0, 61.0, 62.0, 62.0, 62.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 87.0, 89.0, 89.0, 87.0, 90.0, 88.0, 85.0, 92.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 148.0, 163.0, 139.0, 160.0, 167.0, 275.0, 175.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 202.0, 201.0, 225.0, 262.0, 203.0, 200.0, 204.0, 197.0, 200.0], "value": 209.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 73.0, 73.0, 92.0, 104.0, 62.0, 89.0, 107.0, 107.0], "value": 85.2, "unit": "score"}]}]} 16:21:22 INFO - Return code: 0 16:21:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:23 ERROR - # TBPL SUCCESS # 16:21:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:21:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:21:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:21:23 INFO - 2017-04-03 16:21:23.051 screenresolution[3047:24779] starting screenresolution argv=screenresolution get 16:21:23 INFO - 2017-04-03 16:21:23.054 screenresolution[3047:24779] Display 0: 1600x1200x32@60 16:21:23 INFO - 2017-04-03 16:21:23.060 screenresolution[3048:24782] starting screenresolution argv=screenresolution list 16:21:23 INFO - Available Modes on Display 0 16:21:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:21:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:21:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:21:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:21:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:21:23 INFO - Intel Iris: 16:21:23 INFO - Chipset Model: Intel Iris 16:21:23 INFO - Type: GPU 16:21:23 INFO - Bus: Built-In 16:21:23 INFO - VRAM (Dynamic, Max): 1536 MB 16:21:23 INFO - Vendor: Intel (0x8086) 16:21:23 INFO - Device ID: 0x0a2e 16:21:23 INFO - Revision ID: 0x0009 16:21:23 INFO - Displays: 16:21:23 INFO - Display: 16:21:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:21:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:21:23 INFO - Display Serial Number: 1600x1200 60 16:21:23 INFO - Main Display: Yes 16:21:23 INFO - Mirror: Off 16:21:23 INFO - Online: Yes 16:21:23 INFO - Rotation: Supported 16:21:23 INFO - Return code: 0 16:21:23 INFO - Running post-action listener: _resource_record_post_action 16:21:23 INFO - [mozharness: 2017-04-03 23:21:23.299838Z] Finished run-tests step (success) 16:21:23 INFO - Running post-run listener: _resource_record_post_run 16:21:23 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 262144; Write bytes: 517945344; Read time: 2; Write time: 1583 16:21:23 INFO - TinderboxPrint: CPU usage
54.2% 16:21:23 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 2 16:21:23 INFO - TinderboxPrint: I/O write bytes / time
517,945,344 / 1,583 16:21:23 INFO - TinderboxPrint: CPU idle
2,199.1 (45.8%) 16:21:23 INFO - TinderboxPrint: CPU system
971.6 (20.2%) 16:21:23 INFO - TinderboxPrint: CPU user
1,635.0 (34.0%) 16:21:23 INFO - TinderboxPrint: Swap in / out
211,406,848 / 0 16:21:23 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 212601344; Write bytes: 215365120; Read time: 16701; Write time: 465 16:21:23 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 225280; Write bytes: 301199872; Read time: 2; Write time: 1110 16:21:23 INFO - Running post-run listener: _upload_blobber_files 16:21:23 INFO - Blob upload gear active. 16:21:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:24 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:21:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:25 INFO - (blobuploader) - INFO - Done attempting. 16:21:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:21:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:21:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:26 INFO - (blobuploader) - INFO - Done attempting. 16:21:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:21:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:21:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:27 INFO - (blobuploader) - INFO - Done attempting. 16:21:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:29 INFO - (blobuploader) - INFO - Done attempting. 16:21:29 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:29 INFO - Return code: 0 16:21:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db551567b00a98e857791a3854fdd362978bb2e8523de70ad3f0bbdc1ef2a564539546b8fb2918110882d648a05481fa000d40e2218e675ddda2e2b1ab425182", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1424257f8a912de6a0cb43b38fb93b9efafc99d388920f6b62fc213e9834123f6a33b2cb3a7393fc1c2a75b728dd4bb2ff0c56c05b50d050bd6a27a487dde825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a1af287833a0275b712e5c73e39e03a7acccea8274957550212a2f34085467944ec274935c83d8fb2adb482ba68dc0c5099893794c4cd61564be120f9e4b79f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3562420ec6f6954cc4f84405c570778ff0475d0faaa1ebd3d9c8f42d87787a4105eb2acb1a0c0a229f951198d6b44bdc21bde6c21b9960d8ac307453e7e00"} 16:21:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:29 INFO - Contents: 16:21:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db551567b00a98e857791a3854fdd362978bb2e8523de70ad3f0bbdc1ef2a564539546b8fb2918110882d648a05481fa000d40e2218e675ddda2e2b1ab425182", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1424257f8a912de6a0cb43b38fb93b9efafc99d388920f6b62fc213e9834123f6a33b2cb3a7393fc1c2a75b728dd4bb2ff0c56c05b50d050bd6a27a487dde825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a1af287833a0275b712e5c73e39e03a7acccea8274957550212a2f34085467944ec274935c83d8fb2adb482ba68dc0c5099893794c4cd61564be120f9e4b79f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3562420ec6f6954cc4f84405c570778ff0475d0faaa1ebd3d9c8f42d87787a4105eb2acb1a0c0a229f951198d6b44bdc21bde6c21b9960d8ac307453e7e00"} 16:21:29 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:29 INFO - Copying logs to upload dir... 16:21:29 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.771259 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-03 16:21:29.339592) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 16:21:29.343341) ========= 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.N5GflL1SUc/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.z7piX0uHxH/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/db551567b00a98e857791a3854fdd362978bb2e8523de70ad3f0bbdc1ef2a564539546b8fb2918110882d648a05481fa000d40e2218e675ddda2e2b1ab425182", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1424257f8a912de6a0cb43b38fb93b9efafc99d388920f6b62fc213e9834123f6a33b2cb3a7393fc1c2a75b728dd4bb2ff0c56c05b50d050bd6a27a487dde825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a1af287833a0275b712e5c73e39e03a7acccea8274957550212a2f34085467944ec274935c83d8fb2adb482ba68dc0c5099893794c4cd61564be120f9e4b79f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3562420ec6f6954cc4f84405c570778ff0475d0faaa1ebd3d9c8f42d87787a4105eb2acb1a0c0a229f951198d6b44bdc21bde6c21b9960d8ac307453e7e00"} build_url:https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011670 build_url: 'https://queue.taskcluster.net/v1/task/LPl9k9VVS1-jQh_CLMrDow/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/db551567b00a98e857791a3854fdd362978bb2e8523de70ad3f0bbdc1ef2a564539546b8fb2918110882d648a05481fa000d40e2218e675ddda2e2b1ab425182", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1424257f8a912de6a0cb43b38fb93b9efafc99d388920f6b62fc213e9834123f6a33b2cb3a7393fc1c2a75b728dd4bb2ff0c56c05b50d050bd6a27a487dde825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a1af287833a0275b712e5c73e39e03a7acccea8274957550212a2f34085467944ec274935c83d8fb2adb482ba68dc0c5099893794c4cd61564be120f9e4b79f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/84d3562420ec6f6954cc4f84405c570778ff0475d0faaa1ebd3d9c8f42d87787a4105eb2acb1a0c0a229f951198d6b44bdc21bde6c21b9960d8ac307453e7e00"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 16:21:29.378334) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 16:21:29.378630) ========= 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.N5GflL1SUc/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.z7piX0uHxH/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005290 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 16:21:29.430786) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 16:21:29.432061) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 16:21:29.433166) ========= ========= Total master_lag: 0.19 =========