builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0084 starttime: 1485684462.85 results: success (0) buildid: 20170129004005 builduid: c59f6ba3194143789db73d4981548c48 revision: 87c8697b2039d816c1531e5a1edd698933b38663 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:42.848061) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:42.848483) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:42.848807) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:42.876221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:42.876828) ========= 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.rWdEAy49BG/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.koPXsHfqQv/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-01-29 02:07:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.5M=0.001s 2017-01-29 02:07:43 (12.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134059 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:43.036895) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:43.037629) ========= 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.rWdEAy49BG/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.koPXsHfqQv/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.024233 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:43.092141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 02:07:43.092498) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 87c8697b2039d816c1531e5a1edd698933b38663 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 87c8697b2039d816c1531e5a1edd698933b38663 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rWdEAy49BG/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.koPXsHfqQv/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-01-29 02:07:43,193 truncating revision to first 12 chars 2017-01-29 02:07:43,193 Setting DEBUG logging. 2017-01-29 02:07:43,193 attempt 1/10 2017-01-29 02:07:43,193 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/87c8697b2039?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-29 02:07:44,286 unpacking tar archive at: mozilla-aurora-87c8697b2039/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370399 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-29 02:07:44.487362) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:44.488081) ========= script_repo_revision: 87c8697b2039d816c1531e5a1edd698933b38663 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:44.488979) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:44.489321) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-29 02:07:44.509367) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-01-29 02:07:44.509770) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rWdEAy49BG/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.koPXsHfqQv/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:07:44 INFO - MultiFileLogger online at 20170129 02:07:44 in /builds/slave/test 02:07:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:07:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:07:44 INFO - {'append_to_log': False, 02:07:44 INFO - 'base_work_dir': '/builds/slave/test', 02:07:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:07:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:07:44 INFO - 'branch': 'Mozilla-Aurora', 02:07:44 INFO - 'buildbot_json_path': 'buildprops.json', 02:07:44 INFO - 'config_files': ('talos/mac_config.py',), 02:07:44 INFO - 'default_actions': ('clobber', 02:07:44 INFO - 'read-buildbot-config', 02:07:44 INFO - 'download-and-extract', 02:07:44 INFO - 'populate-webroot', 02:07:44 INFO - 'create-virtualenv', 02:07:44 INFO - 'install', 02:07:44 INFO - 'run-tests'), 02:07:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:07:44 INFO - 'download_minidump_stackwalk': True, 02:07:44 INFO - 'download_symbols': 'ondemand', 02:07:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:07:44 INFO - 'tooltool.py': '/tools/tooltool.py', 02:07:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:07:44 INFO - '/tools/misc-python/virtualenv.py')}, 02:07:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:07:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:07:44 INFO - 'installer_path': 'installer.exe', 02:07:44 INFO - 'log_level': 'info', 02:07:44 INFO - 'log_name': 'talos', 02:07:44 INFO - 'log_to_console': True, 02:07:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:07:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:07:44 INFO - 'opt_config_files': (), 02:07:44 INFO - 'pip_index': False, 02:07:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:07:44 INFO - 'cmd': ('bash', 02:07:44 INFO - '-c', 02:07:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:07:44 INFO - 'enabled': True, 02:07:44 INFO - 'halt_on_failure': False, 02:07:44 INFO - 'name': 'check_screen_resolution'},), 02:07:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:07:44 INFO - 'cmd': ('bash', 02:07:44 INFO - '-c', 02:07:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:07:44 INFO - 'enabled': True, 02:07:44 INFO - 'halt_on_failure': False, 02:07:44 INFO - 'name': 'check_screen_resolution'},), 02:07:44 INFO - 'run_cmd_checks_enabled': True, 02:07:44 INFO - 'sps_profile': False, 02:07:44 INFO - 'sps_profile_interval': 0, 02:07:44 INFO - 'suite': 'dromaeojs-e10s', 02:07:44 INFO - 'system_bits': '32', 02:07:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:07:44 INFO - 'title': 't-yosemite-r7-0084', 02:07:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:07:44 INFO - 'use_talos_json': True, 02:07:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:07:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:07:44 INFO - 'work_dir': 'build'} 02:07:44 INFO - [mozharness: 2017-01-29 10:07:44.730234Z] Running clobber step. 02:07:44 INFO - Running pre-action listener: _resource_record_pre_action 02:07:44 INFO - Running main action method: clobber 02:07:44 INFO - rmtree: /builds/slave/test/build 02:07:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:07:47 INFO - Running post-action listener: _resource_record_post_action 02:07:47 INFO - [mozharness: 2017-01-29 10:07:47.276833Z] Finished clobber step (success) 02:07:47 INFO - [mozharness: 2017-01-29 10:07:47.277027Z] Running read-buildbot-config step. 02:07:47 INFO - Running pre-action listener: _resource_record_pre_action 02:07:47 INFO - Running main action method: read_buildbot_config 02:07:47 INFO - Using buildbot properties: 02:07:47 INFO - { 02:07:47 INFO - "project": "", 02:07:47 INFO - "product": "firefox", 02:07:47 INFO - "script_repo_revision": "production", 02:07:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:07:47 INFO - "repository": "", 02:07:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:07:47 INFO - "buildid": "20170129004005", 02:07:47 INFO - "stage_platform": "macosx64", 02:07:47 INFO - "basedir": "/builds/slave/test", 02:07:47 INFO - "nightly_build": "True", 02:07:47 INFO - "buildnumber": 26, 02:07:47 INFO - "slavename": "t-yosemite-r7-0084", 02:07:47 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:07:47 INFO - "platform": "yosemite_r7", 02:07:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:07:47 INFO - "branch": "mozilla-aurora", 02:07:47 INFO - "repo_path": "releases/mozilla-aurora", 02:07:47 INFO - "revision": "87c8697b2039d816c1531e5a1edd698933b38663", 02:07:47 INFO - "pgo_build": "False", 02:07:47 INFO - "builduid": "c59f6ba3194143789db73d4981548c48", 02:07:47 INFO - "slavebuilddir": "test" 02:07:47 INFO - } 02:07:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 02:07:47 INFO - Running post-action listener: _resource_record_post_action 02:07:47 INFO - [mozharness: 2017-01-29 10:07:47.282921Z] Finished read-buildbot-config step (success) 02:07:47 INFO - [mozharness: 2017-01-29 10:07:47.283132Z] Running download-and-extract step. 02:07:47 INFO - Running pre-action listener: _resource_record_pre_action 02:07:47 INFO - Running main action method: download_and_extract 02:07:47 INFO - mkdir: /builds/slave/test/build/tests 02:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:47 INFO - trying https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 02:07:47 INFO - Downloading https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 02:07:48 INFO - Downloaded 1199 bytes. 02:07:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:07:48 INFO - Using the following test package requirements: 02:07:48 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:07:48 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 02:07:48 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 02:07:48 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'jsshell-mac.zip'], 02:07:48 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 02:07:48 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:07:48 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 02:07:48 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 02:07:48 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 02:07:48 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:07:48 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 02:07:48 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:07:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:07:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:07:48 INFO - Fetch https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:07:55 INFO - Expected file size: 16351888 02:07:55 INFO - Obtained file size: 16351888 02:08:02 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:08:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:08:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:02 INFO - Fetch https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:08:03 INFO - Expected file size: 16351888 02:08:03 INFO - Obtained file size: 16351888 02:08:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:08:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:08:09 INFO - Fetch https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:08:21 INFO - Expected file size: 14403485 02:08:21 INFO - Obtained file size: 14403485 02:08: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')]} 02:08:21 INFO - trying https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:08:21 INFO - Downloading https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:08:24 INFO - Downloaded 73974162 bytes. 02:08:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:08:24 INFO - mkdir: /builds/slave/test/properties 02:08:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:24 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:24 INFO - Contents: 02:08:24 INFO - build_url:https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:08:25 INFO - Running post-action listener: _resource_record_post_action 02:08:25 INFO - Running post-action listener: set_extra_try_arguments 02:08:25 INFO - [mozharness: 2017-01-29 10:08:25.808173Z] Finished download-and-extract step (success) 02:08:25 INFO - [mozharness: 2017-01-29 10:08:25.808321Z] Running populate-webroot step. 02:08:25 INFO - Running pre-action listener: _resource_record_pre_action 02:08:25 INFO - Running main action method: populate_webroot 02:08:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:08:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:08:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:08:25 INFO - '--tppagecycles', 02:08:25 INFO - '7'], 02:08:25 INFO - 'tests': ['tsvgm']}}, 02:08:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:08:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:08:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:08:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:08:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['damp', 'tps']}, 02:08:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'tests': ['damp', 'tps']}, 02:08:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['dromaeo_dom']}, 02:08:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:08:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['basic_compositor_video']}, 02:08:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:08:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other-e10s': {'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tsvgx', 02:08:25 INFO - 'tsvgr_opacity', 02:08:25 INFO - 'tart', 02:08:25 INFO - 'tscrollx', 02:08:25 INFO - 'cart', 02:08:25 INFO - 'tsvg_static']}, 02:08:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:08:25 INFO - 'tsvgr_opacity', 02:08:25 INFO - 'tart', 02:08:25 INFO - 'tscrollx', 02:08:25 INFO - 'cart', 02:08:25 INFO - 'tsvg_static']}, 02:08:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tp5o']}, 02:08:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:08:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s', 02:08:25 INFO - '--xperf_path', 02:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:25 INFO - 'tests': ['tp5n']}, 02:08:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:08:25 INFO - 'talos_options': ['--xperf_path', 02:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:25 INFO - 'tests': ['tp5n']}}, 02:08:25 INFO - 'talos.zip': {'path': '', 02:08:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:08:25 INFO - Running post-action listener: _resource_record_post_action 02:08:25 INFO - [mozharness: 2017-01-29 10:08:25.826801Z] Finished populate-webroot step (success) 02:08:25 INFO - [mozharness: 2017-01-29 10:08:25.826918Z] Running create-virtualenv step. 02:08:25 INFO - Running pre-action listener: _resource_record_pre_action 02:08:25 INFO - Running main action method: create_virtualenv 02:08:25 INFO - Creating virtualenv /builds/slave/test/build/venv 02:08:25 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 02:08:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:08:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:08:25 INFO - Using real prefix '/tools/python27' 02:08:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:08:26 INFO - Installing distribute.............................................................................................................................................................................................done. 02:08:29 INFO - Installing pip.................done. 02:08:29 INFO - Return code: 0 02:08:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:08:29 INFO - Reading from file tmpfile_stdout 02:08:29 INFO - Output received: 02:08:29 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) 02:08:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:30 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')]} 02:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:30 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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:30 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 02:08:30 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 02:08:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:30 INFO - 'HOME': '/Users/cltbld', 02:08:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:30 INFO - 'LOGNAME': 'cltbld', 02:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:30 INFO - 'MOZ_NO_REMOTE': '1', 02:08:30 INFO - 'NO_EM_RESTART': '1', 02:08:30 INFO - 'PAGER': '/bin/cat', 02:08:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:30 INFO - 'PWD': '/builds/slave/test', 02:08:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:30 INFO - 'SHELL': '/bin/bash', 02:08:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:30 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:30 INFO - 'USER': 'cltbld', 02:08:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:30 INFO - 'XPC_FLAGS': '0x0', 02:08:30 INFO - 'XPC_SERVICE_NAME': '0', 02:08:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:30 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 02:08:30 INFO - Cleaning up... 02:08:30 INFO - Return code: 0 02:08:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:08:30 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')]} 02:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:30 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')]} 02:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:30 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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:30 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 02:08:30 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 02:08:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:30 INFO - 'HOME': '/Users/cltbld', 02:08:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:30 INFO - 'LOGNAME': 'cltbld', 02:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:30 INFO - 'MOZ_NO_REMOTE': '1', 02:08:30 INFO - 'NO_EM_RESTART': '1', 02:08:30 INFO - 'PAGER': '/bin/cat', 02:08:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:30 INFO - 'PWD': '/builds/slave/test', 02:08:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:30 INFO - 'SHELL': '/bin/bash', 02:08:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:30 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:30 INFO - 'USER': 'cltbld', 02:08:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:30 INFO - 'XPC_FLAGS': '0x0', 02:08:30 INFO - 'XPC_SERVICE_NAME': '0', 02:08:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:30 INFO - Downloading/unpacking psutil>=3.1.1 02:08:30 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 02:08:30 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 02:08:30 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 02:08:30 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 02:08:30 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 02:08:30 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 02:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:08:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:38 INFO - Installing collected packages: psutil 02:08:38 INFO - Running setup.py install for psutil 02:08:38 INFO - building 'psutil._psutil_osx' extension 02:08:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:08:39 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 02:08:39 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 02:08:39 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 02:08:39 INFO - building 'psutil._psutil_posix' extension 02:08:39 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 02:08:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:08:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:08:39 INFO - ^ 02:08:39 INFO - 1 warning generated. 02:08:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:08:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:39 INFO - Successfully installed psutil 02:08:39 INFO - Cleaning up... 02:08:39 INFO - Return code: 0 02:08:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:39 INFO - 'HOME': '/Users/cltbld', 02:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:39 INFO - 'LOGNAME': 'cltbld', 02:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:39 INFO - 'MOZ_NO_REMOTE': '1', 02:08:39 INFO - 'NO_EM_RESTART': '1', 02:08:39 INFO - 'PAGER': '/bin/cat', 02:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:39 INFO - 'PWD': '/builds/slave/test', 02:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:39 INFO - 'SHELL': '/bin/bash', 02:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:39 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:39 INFO - 'USER': 'cltbld', 02:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:39 INFO - 'XPC_FLAGS': '0x0', 02:08:39 INFO - 'XPC_SERVICE_NAME': '0', 02:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:08:44 INFO - Installing collected packages: mozsystemmonitor 02:08:44 INFO - Running setup.py install for mozsystemmonitor 02:08:44 INFO - Successfully installed mozsystemmonitor 02:08:44 INFO - Cleaning up... 02:08:44 INFO - Return code: 0 02:08:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:44 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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:44 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 02:08:44 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 02:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:44 INFO - 'HOME': '/Users/cltbld', 02:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:44 INFO - 'LOGNAME': 'cltbld', 02:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:44 INFO - 'MOZ_NO_REMOTE': '1', 02:08:44 INFO - 'NO_EM_RESTART': '1', 02:08:44 INFO - 'PAGER': '/bin/cat', 02:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:44 INFO - 'PWD': '/builds/slave/test', 02:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:44 INFO - 'SHELL': '/bin/bash', 02:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:44 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:44 INFO - 'USER': 'cltbld', 02:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:44 INFO - 'XPC_FLAGS': '0x0', 02:08:44 INFO - 'XPC_SERVICE_NAME': '0', 02:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:44 INFO - Downloading/unpacking jsonschema==2.5.1 02:08:44 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 02:08:44 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 02:08:44 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 02:08:44 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 02:08:44 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 02:08:44 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 02:08:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:08:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:08:49 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 02:08:49 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 02:08:49 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 02:08:49 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 02:08:49 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 02:08:49 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 02:08:49 INFO - Downloading functools32-3.2.3-2.tar.gz 02:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:08:49 INFO - warning: no files found matching '*.txt' 02:08:49 INFO - no previously-included directories found matching 'build' 02:08:49 INFO - no previously-included directories found matching 'dist' 02:08:49 INFO - no previously-included directories found matching '.git*' 02:08:49 INFO - Installing collected packages: jsonschema, functools32 02:08:49 INFO - Running setup.py install for functools32 02:08:50 INFO - warning: no files found matching '*.txt' 02:08:50 INFO - no previously-included directories found matching 'build' 02:08:50 INFO - no previously-included directories found matching 'dist' 02:08:50 INFO - no previously-included directories found matching '.git*' 02:08:50 INFO - Successfully installed jsonschema functools32 02:08:50 INFO - Cleaning up... 02:08:50 INFO - Return code: 0 02:08:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:50 INFO - 'HOME': '/Users/cltbld', 02:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:50 INFO - 'LOGNAME': 'cltbld', 02:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:50 INFO - 'MOZ_NO_REMOTE': '1', 02:08:50 INFO - 'NO_EM_RESTART': '1', 02:08:50 INFO - 'PAGER': '/bin/cat', 02:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:50 INFO - 'PWD': '/builds/slave/test', 02:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:50 INFO - 'SHELL': '/bin/bash', 02:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:50 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:50 INFO - 'USER': 'cltbld', 02:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:50 INFO - 'XPC_FLAGS': '0x0', 02:08:50 INFO - 'XPC_SERVICE_NAME': '0', 02:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:08:50 INFO - Cleaning up... 02:08:50 INFO - Return code: 0 02:08:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:08:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:50 INFO - 'HOME': '/Users/cltbld', 02:08:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:50 INFO - 'LOGNAME': 'cltbld', 02:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:50 INFO - 'MOZ_NO_REMOTE': '1', 02:08:50 INFO - 'NO_EM_RESTART': '1', 02:08:50 INFO - 'PAGER': '/bin/cat', 02:08:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:50 INFO - 'PWD': '/builds/slave/test', 02:08:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:50 INFO - 'SHELL': '/bin/bash', 02:08:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:50 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:50 INFO - 'USER': 'cltbld', 02:08:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:50 INFO - 'XPC_FLAGS': '0x0', 02:08:50 INFO - 'XPC_SERVICE_NAME': '0', 02:08:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:50 INFO - Downloading/unpacking blobuploader==1.2.4 02:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:08:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:08:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:54 INFO - Downloading docopt-0.6.1.tar.gz 02:08:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:08:54 INFO - Installing collected packages: blobuploader, requests, docopt 02:08:54 INFO - Running setup.py install for blobuploader 02:08:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:08:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:08:54 INFO - Running setup.py install for requests 02:08:55 INFO - Running setup.py install for docopt 02:08:55 INFO - Successfully installed blobuploader requests docopt 02:08:55 INFO - Cleaning up... 02:08:55 INFO - Return code: 0 02:08:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:55 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')]} 02:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:55 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')]} 02:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:55 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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:08:55 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 02:08:55 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 02:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:55 INFO - 'HOME': '/Users/cltbld', 02:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:55 INFO - 'LOGNAME': 'cltbld', 02:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:55 INFO - 'MOZ_NO_REMOTE': '1', 02:08:55 INFO - 'NO_EM_RESTART': '1', 02:08:55 INFO - 'PAGER': '/bin/cat', 02:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:55 INFO - 'PWD': '/builds/slave/test', 02:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:55 INFO - 'SHELL': '/bin/bash', 02:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:08:55 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:55 INFO - 'USER': 'cltbld', 02:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:55 INFO - 'XPC_FLAGS': '0x0', 02:08:55 INFO - 'XPC_SERVICE_NAME': '0', 02:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:55 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Dw1TJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:55 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-OP1ARk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-n0xSuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-FkNgx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-AnL8JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-rXMr68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-z9oMAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-blN6ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-PQsjxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jMCPWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:56 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-wm8YoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:57 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-sw4Z5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:57 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-N0RKL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:57 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-EhmPYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:57 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-3nvyF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:57 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-luq0pR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:57 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-zTU4f2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:08:57 INFO - Running setup.py install for manifestparser 02:08:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozcrash 02:08:57 INFO - Running setup.py install for mozdebug 02:08:58 INFO - Running setup.py install for mozdevice 02:08:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozfile 02:08:58 INFO - Running setup.py install for mozhttpd 02:08:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozinfo 02:08:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozInstall 02:08:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozleak 02:08:58 INFO - Running setup.py install for mozlog 02:08:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Running setup.py install for moznetwork 02:08:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Running setup.py install for mozprocess 02:08:59 INFO - Running setup.py install for mozprofile 02:08:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Running setup.py install for mozrunner 02:08:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Running setup.py install for mozscreenshot 02:08:59 INFO - Running setup.py install for moztest 02:09:00 INFO - Running setup.py install for mozversion 02:09:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:09:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:09:00 INFO - Cleaning up... 02:09:00 INFO - Return code: 0 02:09:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:00 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')]} 02:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:00 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')]} 02:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:00 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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:09:00 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 02:09:00 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 02:09:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:09:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:00 INFO - 'HOME': '/Users/cltbld', 02:09:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:00 INFO - 'LOGNAME': 'cltbld', 02:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:00 INFO - 'MOZ_NO_REMOTE': '1', 02:09:00 INFO - 'NO_EM_RESTART': '1', 02:09:00 INFO - 'PAGER': '/bin/cat', 02:09:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:00 INFO - 'PWD': '/builds/slave/test', 02:09:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:00 INFO - 'SHELL': '/bin/bash', 02:09:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:09:00 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:09:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:00 INFO - 'USER': 'cltbld', 02:09:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:00 INFO - 'XPC_FLAGS': '0x0', 02:09:00 INFO - 'XPC_SERVICE_NAME': '0', 02:09:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:09:00 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Te9lP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:09:00 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-hBU0ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:09:00 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-iaqYfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:09:00 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-hGuw65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:09:00 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-cXw0BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-K5UqcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-oCay9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gJOkNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1hdNkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-evD61b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1a1AlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-RAHSUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-wfMy5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:01 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Ukh9aD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-xZlcC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:02 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)) 02:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-p8_SL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:02 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)) 02:09:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:02 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-BYObar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 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)) 02:09:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09:04 INFO - Downloading blessings-1.6.tar.gz 02:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:09:05 INFO - Installing collected packages: blessings 02:09:05 INFO - Running setup.py install for blessings 02:09:05 INFO - Successfully installed blessings 02:09:05 INFO - Cleaning up... 02:09:05 INFO - Return code: 0 02:09:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:05 INFO - Reading from file tmpfile_stdout 02:09:05 INFO - Current package versions: 02:09:05 INFO - blessings == 1.6 02:09:05 INFO - blobuploader == 1.2.4 02:09:05 INFO - docopt == 0.6.1 02:09:05 INFO - functools32 == 3.2.3-2 02:09:05 INFO - jsonschema == 2.5.1 02:09:05 INFO - manifestparser == 1.1 02:09:05 INFO - mozInstall == 1.12 02:09:05 INFO - mozcrash == 1.0 02:09:05 INFO - mozdebug == 0.1 02:09:05 INFO - mozdevice == 0.49 02:09:05 INFO - mozfile == 1.2 02:09:05 INFO - mozhttpd == 0.7 02:09:05 INFO - mozinfo == 0.9 02:09:05 INFO - mozleak == 0.1 02:09:05 INFO - mozlog == 3.4 02:09:05 INFO - moznetwork == 0.27 02:09:05 INFO - mozprocess == 0.24 02:09:05 INFO - mozprofile == 0.28 02:09:05 INFO - mozrunner == 6.13 02:09:05 INFO - mozscreenshot == 0.1 02:09:05 INFO - mozsystemmonitor == 0.3 02:09:05 INFO - moztest == 0.8 02:09:05 INFO - mozversion == 1.4 02:09:05 INFO - psutil == 3.1.1 02:09:05 INFO - requests == 1.2.3 02:09:05 INFO - wsgiref == 0.1.2 02:09:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:05 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 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:09:05 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 02:09:05 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 02:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:05 INFO - 'HOME': '/Users/cltbld', 02:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:05 INFO - 'LOGNAME': 'cltbld', 02:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:05 INFO - 'MOZ_NO_REMOTE': '1', 02:09:05 INFO - 'NO_EM_RESTART': '1', 02:09:05 INFO - 'PAGER': '/bin/cat', 02:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:05 INFO - 'PWD': '/builds/slave/test', 02:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:05 INFO - 'SHELL': '/bin/bash', 02:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:09:05 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:05 INFO - 'USER': 'cltbld', 02:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:05 INFO - 'XPC_FLAGS': '0x0', 02:09:05 INFO - 'XPC_SERVICE_NAME': '0', 02:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 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)) 02:09:05 INFO - Cleaning up... 02:09:05 INFO - Return code: 0 02:09:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105310b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105313768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb528575260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, '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.rWdEAy49BG/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.koPXsHfqQv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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 02:09: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', 'jsonschema'] in /builds/slave/test/build 02:09: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 jsonschema 02:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:05 INFO - 'HOME': '/Users/cltbld', 02:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:05 INFO - 'LOGNAME': 'cltbld', 02:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:05 INFO - 'MOZ_NO_REMOTE': '1', 02:09:05 INFO - 'NO_EM_RESTART': '1', 02:09:05 INFO - 'PAGER': '/bin/cat', 02:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:05 INFO - 'PWD': '/builds/slave/test', 02:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:05 INFO - 'SHELL': '/bin/bash', 02:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:09:05 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:05 INFO - 'USER': 'cltbld', 02:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:05 INFO - 'XPC_FLAGS': '0x0', 02:09:05 INFO - 'XPC_SERVICE_NAME': '0', 02:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:09:06 INFO - Cleaning up... 02:09:06 INFO - Return code: 0 02:09:06 INFO - Running post-action listener: _resource_record_post_action 02:09:06 INFO - Running post-action listener: _start_resource_monitoring 02:09:06 INFO - Starting resource monitoring. 02:09:06 INFO - [mozharness: 2017-01-29 10:09:06.192044Z] Finished create-virtualenv step (success) 02:09:06 INFO - [mozharness: 2017-01-29 10:09:06.192991Z] Running install step. 02:09:06 INFO - Running pre-action listener: _resource_record_pre_action 02:09:06 INFO - Running main action method: install 02:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:06 INFO - Reading from file tmpfile_stdout 02:09:06 INFO - Detecting whether we're running mozinstall >=1.0... 02:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:09:06 INFO - Reading from file tmpfile_stdout 02:09:06 INFO - Output received: 02:09:06 INFO - Usage: mozinstall [options] installer 02:09:06 INFO - Options: 02:09:06 INFO - -h, --help show this help message and exit 02:09:06 INFO - -d DEST, --destination=DEST 02:09:06 INFO - Directory to install application into. [default: 02:09:06 INFO - "/builds/slave/test"] 02:09:06 INFO - --app=APP Application being installed. [default: firefox] 02:09:06 INFO - mkdir: /builds/slave/test/build/application 02:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:09:26 INFO - Reading from file tmpfile_stdout 02:09:26 INFO - Output received: 02:09:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:09:26 INFO - Running post-action listener: _resource_record_post_action 02:09:26 INFO - [mozharness: 2017-01-29 10:09:26.439946Z] Finished install step (success) 02:09:26 INFO - [mozharness: 2017-01-29 10:09:26.440086Z] Running run-tests step. 02:09:26 INFO - Running pre-action listener: _resource_record_pre_action 02:09:26 INFO - Running main action method: run_tests 02:09:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:09:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:09:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:09:26 INFO - 2017-01-29 02:09:26.453 screenresolution[1628:8406] starting screenresolution argv=screenresolution get 02:09:26 INFO - 2017-01-29 02:09:26.455 screenresolution[1628:8406] Display 0: 1600x1200x32@60 02:09:26 INFO - 2017-01-29 02:09:26.461 screenresolution[1629:8408] starting screenresolution argv=screenresolution list 02:09:26 INFO - Available Modes on Display 0 02:09:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:09:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:09:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:09:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:09:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:09:26 INFO - Intel Iris: 02:09:26 INFO - Chipset Model: Intel Iris 02:09:26 INFO - Type: GPU 02:09:26 INFO - Bus: Built-In 02:09:26 INFO - VRAM (Dynamic, Max): 1536 MB 02:09:26 INFO - Vendor: Intel (0x8086) 02:09:26 INFO - Device ID: 0x0a2e 02:09:26 INFO - Revision ID: 0x0009 02:09:26 INFO - Displays: 02:09:26 INFO - Display: 02:09:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:09:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:09:26 INFO - Display Serial Number: 1600x1200 60 02:09:26 INFO - Main Display: Yes 02:09:26 INFO - Mirror: Off 02:09:26 INFO - Online: Yes 02:09:26 INFO - Rotation: Supported 02:09:26 INFO - Return code: 0 02:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:09:26 INFO - Python 2.7.3 02:09:26 INFO - Return code: 0 02:09:26 INFO - grabbing minidump binary from tooltool 02:09:26 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')]} 02:09:26 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 0x1050eed30>, '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 0x105015db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050e3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:09:26 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 02:09:26 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 02:09:26 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 02:09:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:09:26 INFO - Return code: 0 02:09:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:09:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:09:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:09:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:09:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 02:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 02:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rWdEAy49BG/Render', 02:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:26 INFO - 'HOME': '/Users/cltbld', 02:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:26 INFO - 'LOGNAME': 'cltbld', 02:09:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:26 INFO - 'MOZ_NO_REMOTE': '1', 02:09:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:26 INFO - 'NO_EM_RESTART': '1', 02:09:26 INFO - 'PAGER': '/bin/cat', 02:09:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:26 INFO - 'PWD': '/builds/slave/test', 02:09:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:26 INFO - 'SHELL': '/bin/bash', 02:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.koPXsHfqQv/Listeners', 02:09:26 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 02:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:26 INFO - 'USER': 'cltbld', 02:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:26 INFO - 'XPC_FLAGS': '0x0', 02:09:26 INFO - 'XPC_SERVICE_NAME': '0', 02:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 02:09:27 INFO - mozversion application_buildid: 20170129004005 02:09:27 INFO - mozversion application_changeset: 87c8697b2039d816c1531e5a1edd698933b38663 02:09:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:09:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:09:27 INFO - mozversion application_name: Firefox 02:09:27 INFO - mozversion application_remotingname: firefox-dev 02:09:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:09:27 INFO - mozversion application_vendor: Mozilla 02:09:27 INFO - mozversion application_version: 53.0a2 02:09:27 INFO - mozversion platform_buildid: 20170129004005 02:09:27 INFO - mozversion platform_changeset: 87c8697b2039d816c1531e5a1edd698933b38663 02:09:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:09:27 INFO - mozversion platform_version: 53.0a2 02:09:27 INFO - using testdate: 1485684567 02:09:27 INFO - actual date: 1485684567 02:09:27 INFO - starting webserver on 'localhost:49241' 02:09:27 INFO - SUITE-START | Running 2 tests 02:09:27 INFO - TEST-START | dromaeo_css 02:09:27 INFO - operating with platform_type : mac_ 02:09:27 INFO - Initialising browser for dromaeo_css test... 02:09:27 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp7BE68c/profile http://localhost:49241/getInfo.html) 02:09:29 INFO - PROCESS | 1639 | 2017-01-29 02:09:29.926 plugin-container[1643:8599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x924b, name = 'com.apple.tsm.portname' 02:09:29 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:29 INFO - PROCESS | 1639 | 2017-01-29 02:09:29.926 plugin-container[1643:8599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.CFPasteboardClient' 02:09:29 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:29 INFO - PROCESS | 1639 | 2017-01-29 02:09:29.926 plugin-container[1643:8599] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:09:29 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 02:09:29 INFO - PROCESS | 1639 | colorDepth:24 02:09:29 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 02:09:29 INFO - PROCESS | 1639 | __metrics 02:09:30 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 02:09:30 INFO - TEST-INFO | 1639: exit 0 02:09:30 INFO - Browser initialized. 02:09:30 INFO - Running cycle 1/1 for dromaeo_css test... 02:09:30 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp7BE68c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:33 INFO - PROCESS | 1644 | 2017-01-29 02:09:33.217 plugin-container[1645:8728] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 02:09:33 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:33 INFO - PROCESS | 1644 | 2017-01-29 02:09:33.217 plugin-container[1645:8728] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 02:09:33 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:09:33 INFO - PROCESS | 1644 | 2017-01-29 02:09:33.217 plugin-container[1645:8728] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:09:42 INFO - PROCESS | 1644 | RSS: Main: 218906624 02:09:42 INFO - PROCESS | 1644 | 02:12:44 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:12:46 INFO - PROCESS | 1644 | RSS: Main: 240050176 02:12:46 INFO - PROCESS | 1644 | 02:14:31 INFO - PROCESS | 1644 | ************************* 02:14:31 INFO - PROCESS | 1644 | A coding exception was thrown and uncaught in a Task. 02:14:31 INFO - PROCESS | 1644 | 02:14:31 ERROR - PROCESS | 1644 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:14:31 INFO - PROCESS | 1644 | Full stack: 02:14:31 INFO - PROCESS | 1644 | ************************* 02:15:49 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:15:49 INFO - PROCESS | 1644 | RSS: Main: 247877632 02:15:49 INFO - PROCESS | 1644 | 02:18:51 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:18:52 INFO - PROCESS | 1644 | RSS: Main: 254631936 02:18:52 INFO - PROCESS | 1644 | 02:21:54 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:21:54 INFO - PROCESS | 1644 | RSS: Main: 251105280 02:21:54 INFO - PROCESS | 1644 | 02:24:57 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:24:58 INFO - PROCESS | 1644 | RSS: Main: 255111168 02:24:58 INFO - PROCESS | 1644 | 02:28:00 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:28:01 INFO - PROCESS | 1644 | RSS: Main: 256733184 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | __start_tp_report 02:28:01 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 02:28:01 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 02:28:01 INFO - PROCESS | 1644 | |i|pagename|runs| 02:28:01 INFO - PROCESS | 1644 | |0;dojo.html;2983.656879458862;3364.747643926706;3382.238996225848;3396.7961922284667;3397.3205358928203;4655.394798676176;5662.546996240301;5663.065594176961;5689.374168841429;5766.481016708506;5720.970225359433;5791.942080579193;5802.622829516078;5805;5824.970875145618;5204.32343795306;5264.131418315973;5279.604029697788;5288.286081379003;5330.1205301125265;5336.066188417031;5348.759305831228;5365.543928766059;5387.542058924996;5393.541548968343;5190.143626301656;5214.217867319913;5332.466753324676;5333.813316533922;5336.092864213074;5252.028374750684;5259.447757985414;5278.868028299285;5331.253624492574;5360.303160589098;4327.848525301631;5169.4388503517985;5254.894902101936;5255.474452554752;5327.227552004976;4295.677824163169;5212.869678258036;5247.661846229225;5794.82615521535;5802.245708057968;4638.768061596907;5921.437463440966;6109.890546867989;6116.244470650298;6239.719212635442;6248.3751624837605;6249.0626406039;6257.43683068526;6266.40469155434;6267.4986001119805;5809.82570522885;5824.097264923944;5830.067189249699;5833.474987251126;5877.206577112078;6193.81418557444;6217.595856269328;6235.501159907197;6266.843328916813;6274.247090349188;6126.6017708848785;6132.127275087106;6165.260168779775;6168.537359698041;6180.134781130645;6225.844353891099;6267.65527895961;6285.96857015712;6290.308066112724;6295.527835412271;5119.97440012805;6037.45662890342;6119.045604113885;6128.142060111588;6132.938670613326;5496.587755918322;6220.25356957159;6229.844253893689;6232.657203853832;6237.594556353823;4986.227134794112;4995.0509403214;4996.300517927493;4999.825006124768;5008.1486147355035;4410.867673969786;4418.315161150027;4427.269500532376;4492;4512.029913568598;5714.257146570919;5716.628419152742;5786.826395208151;5815.098659707752;5826.300843898759;6213.96893015532;6236.126942228092;6238.251409830849;6250.74997000124;6253.155823963798;5656.688882111441;5709.286339207599;5739.397363276879;5774.509166720875;5774.884502310014;5473.06957817172;5490.039243132515;5504.889902201933;5507;5510.9448905511235;6315.242170939517;6319.842003949938;6340.239171299474;6347;6360.713767880364;6113.293877754158;6172.105044768573;6185.2886918003205;6198.194234749454;6290.1508296381235;6252.249730032425;6276.592021518587;6288.402601752826;6288.968555157195;6298.559100862895;6174.043023331391;6182.227221597301;6200.100985357008;6207.34822843613;6225;6105.908411373922;6151.96924015377;6179.721912514037;6264.123022776906;6277.466415354746;6052.66710330936;6119.68535298207;6138.633639632548;6140.657627863787;6143.972489078683;4861.392325959255;6082.878342433038;6117.541184411276;6134.14122022926;6137.815865524041;4876.975835074674;5810.912836307543;5920.200772895776;6006.639601623917;6025.909611355745;5965.463108320272;5996.490298324691;6025.005874030736;6033.276006879202;6086.239220097488;5901.3213480448585;5910.438508341701;5910.438508341701;5911.586188966731;5935.732892019956 02:28:01 INFO - PROCESS | 1644 | |1;ext.html;433.51446380054324;445.4031597659137;477.4175505882824;505.32286735774056;536.6994483089472;38628.22743545128;42208.577914220885;42478.93802654935;42505.78747106268;43070.13859722808;39737.602623973755;43270.91822704426;43868.34197487032;43870.34194487077;43872.3419148713;8373.995120585538;8583.313334933222;8812.8678069829;9478.28912831537;9480.81038379232;11045.723856903562;11411.25826821254;11424.714382140472;11573;11584.015358694522;9732.854007189897;11396.487158077907;11478.770424591503;11495.19533632646;11498;11440.14198935079;11485;11498.770024599546;11504.942475287653;11532.308061516294;11353.772924541463;11415.54337826486;11426.485808138654;11429.02853257474;11433.199676022681;41808.95477613054;41819.58180418187;42124.368134478005;42184.57815421868;42194.36708449376;4033.2740106780766;4122;4127.855525056609;4192.559781222958;4270.636995855356;30553.541696874563;30576.929807456854;31781.52327715086;31824.363512729615;32007.51988720171;3157.952630710541;3186.314942287396;3281.3109247058146;3286.753493487974;3297.505374193866;381753;385773.14226857654;386170;386892;387631.0618446918;66677.6666116676;66914.66170676559;66975.66512167409;67097.3290267091;67224.66387668031;69518;70159.29840701527;70254;70262.2973770266;70430;16778.90937089085;17233.655326893393;17336.65326693459;17377.91311043462;17390.65218695619;11803.40982950849;12051.397430128458;12757.872421275853;13088.803667944989;13201;17403.129843507773;18556.628867422576;18769.90615046916;18827.905860470884;18863.3397831078;7068.363847253669;7124.358807707227;7147.820609599462;7211.387032102225;7212.4951253411755;829.2909562324246;874.208840998897;881.7398867334152;906.2885634776669;908.1236606674458;25869.482610347688;27347.04285350043;27401.492917889707;29139.27151821179;30555;947.3657940053353;972.4845831709206;1048.7011201807447;1080.330195278932;1081.1134869407167;13804.447822087204;15459.845401545841;15988.040717556984;15993.60015999586;16197.94713343629;642951;643952;647175;650140;650233;63598;63676;63729.725405491634;63941.68029159824;63957.680211599574;1423.3808293392408;1434.400643282753;1438.2736717957573;1453.5130731205165;1459.6350912271719;29357.412851743273;30209;30472.84763576168;30599.694003060577;30692.539611906286;53397;53886.73056634849;53955.190672140736;54016.45983540272;54131.72934135304;555551;560657;562343;562753;563155;21145.25991590287;24838.627420588346;24898.751012490367;25489.745102548735;38143.80928095342;35288.11779705528;43978.780106099264;44407.111857762015;44448;44779.32831007472;28657.570136447554;35819.64180358163;36895.07762305856;37745.05637359088;37810.054748631504;36995.44506832454;37091.44362834613;37187.25625487529;37542.24915501729;37938.62061379351;35147.82426087853;35493.645063550066;35510.289794203454;35850.46224306586;35872.64127358797;31681;32837.50743738894;33128;33466.83266583652;33474.832625836716;24394.02423903024;24627.876860615583;24662.64355460395;24715.62926556067;24788.876055620327 02:28:01 INFO - PROCESS | 1644 | |2;jquery.html;393.3097414038362;394.4379259555133;395.9524857017158;402.47276068350465;421.0001247038284;16368.590785230346;19129;19892.50268743282;20398.89800550999;20500.38498845035;20887.060082296295;20907.68638470424;21455.356339309845;21488.355349339505;21725.67411488829;5190.377154741436;5471.917921231185;5473.917891231635;5505.476979686923;5567.220589117527;5344.7862085516535;5553.055980483307;5555.194496797982;5569.916451253234;5585;5416.1063424534905;5496.835094947158;5541.445855414447;5549.112142057272;5560.555155587544;5458.890822183574;5525.696086715229;5531.0873705838485;5576.247206627094;6010.398960103998;5403.729813509309;5414.35027796663;5415.079436495806;5419.512243898068;5425.267588875491;13578.660533486742;18253.99603021833;18479;25295.62056569153;26035.218943431537;2508.209913877141;2648.523265812172;2649.774769144625;2659.587763896599;2791.567307067408;9765.951170244174;10150.949245253727;10248.436336001518;10519.684409467729;10662.840057399146;1882.4446788197515;1883.1243471785685;1883.4349695091419;1895.2229585869727;1911.7419148415065;114656.42671786588;115077;120138;120792;121878.99999999911;9945.154661853823;11049.834252486218;11056.336619802676;11634.825477617842;11976.221545599508;14156;14797.408103675732;15604.609884752745;15651.530454086396;15714.21428928549;8541.615627296787;8818.647254109765;8830.955845220862;8875.378723489293;9039.367244292836;7250.492465527468;8693.91306086931;8699.695510657097;8742.781430464289;8885.955570222237;4770.332153498513;4851.343384148901;4856;4862.358909195588;4973.776180071905;1582.1219223331161;1894.4316704988448;2025.6961455781457;2056.7737548869613;2088.3421722157777;957.4686049242598;977.2524019125375;986.8798913233388;989.1493315748451;1004.0311099788739;13196.538121165713;13464.249647545756;13592.45630174782;13693.452261909611;14035.71928561423;981.7202097402206;983.2674657380297;984.4437892590663;993.6224234790733;1000.2198285337448;7011.5091943564485;7015.298470153046;7100.751473698396;7184.209736928934;7189;581345;609085;630829;692630;713320;129247;133110;133166;138254.61745382618;143295;1053.2416660004785;1101.5208384352673;1111.8054340490544;1112.6940091474746;1115.2360632966445;19205.231790728772;19286.517837054187;20243.089060992585;20331.390058298275;20336.08487618031;31482;39365.60634393734;54370.81257729112;57250.42749572451;58419.70790146022;64027.679861600394;82200;83478.58260708657;85513;86290.56854715938;6087.8782424350375;6888.448924086161;7053.4004609608755;7256.637168141678;7371.115466144097;8828.293736501193;11933.940330298294;12475.875241247471;12526.749465010467;12994.480220791062;9692.273079519206;10849.240553161202;10875.564977400816;10879.728006799893;11192.88807111951;10626.73433164177;10781.02970732638;10818.837717434402;10832.025117739442;10886.96573825517;10795.460226988776;11030.172737044593;11189.88810111921;11446.37044962535;11503.907128822748;6805.761798337035;7786;8099.14958929304;8120.878186827084;8149.8182763500145;9413.623455061721;10149.797004060025;10150.593976240867;10358.378497290187;10420.635277765241 02:28:01 INFO - PROCESS | 1644 | |3;mootools.html;1426.7574512332903;1431.798436142485;1445.624137724192;1460.277162804412;1465.5530063330687;7489.325960663539;7725.497842640239;7990.161033091522;8429.747107586767;8438.915610843897;7963.442559020871;8260.958695206531;8266.090730019692;8348.12344703805;8385.077641459435;2306.4464528513163;2314.687517185184;2345.9765402346015;2346.9530609387803;2356.846804957681;2335.322756400642;2356.9410764730933;2367.621180611103;2367.6803631509783;2372.738998710149;2302.366849116498;2311.5783792967286;2346.061575369857;2346.495503466754;2352.1179557665873;1839.8896066236068;1944.8249657530748;2244.9887750561224;2292.3695983604644;2312.144506532594;2080.6566916458764;2256.4584499720113;2283.2693538067824;2359.3865594945437;2369.822263330257;8421.989361276625;8425.368097392658;8449.112843151504;8491.787705307355;8511.872321915176;4672.322513235562;4937.111319962405;5047.3438453001245;5192.480751924815;5205.375354957429;3134.9216269593217;3176.28533579945;3514.1917359007316;3558.430651095812;3572.7677700949357;1137.8919002694731;1235.8269842222096;1726.413019573351;1746.97379539307;1832.4411054628333;9434.622615095388;10032.49837508129;10816.459177041195;10896.346219226873;10900.291481053706;4578.313253012008;4755.95244047562;4758.714477131383;4779.259214821708;4813.278008298713;7660.659384607671;8776.122387761301;8785.253253473526;8828.823423531494;8834.425762325429;5056.797728090909;5156.607715916756;5158.638895277295;5162.1224391853475;5172.663776854492;4513.210188217049;4661.836835710734;4729.645276604199;4756.857294281177;4815.253635686474;3666.175110600094;3750.4936833527127;3762.811859407019;3779.3764028935466;3809.12390150267;1166.2710805746408;1168.5209064283604;1177.4936777185721;1178.269472926791;1178.776032553812;803.3087960820022;858.3176374782063;905.3391024552113;924.5469719837231;930.5905401623264;4820.975895120502;5484.985277723634;5628.774849005994;5680.857978550486;5681.801136960186;969.4910172159673;996.5599708421262;1014.8071866345371;1031.9432431216205;1050.8056009638242;3701.6668499835146;3765.9623403765613;3835.060410199519;3861.8841434757;3873.845046198177;9795.608175673036;10114.140298074599;10142.949285253526;10148.137408320375;10152.03555662211;9818.067283608047;9907.343189449934;9940.453275069796;10081.546330415149;10120.898791012143;6555.868882622321;7703.1911649277135;7789;7976.601169941479;7997.240262175084;5475.534579560782;5515.338159420895;5540.972295138498;5623.072193088258;5631.239782629456;9125.269978401844;9157.908420915972;9229.123233292827;9311.394759340621;9336.439813611205;8065.919340806517;8299.95850020746;8516.574171291535;8823.411785878603;9297.02381249959;5797.797077102281;7199.532030418006;7227;7247.420206383371;7296.489245752747;7926.326262267772;7962.681492740226;8060.1133875274845;8061.314788243065;8083.797905052421;7136.964315178391;7140.178879428932;7183.640817958976;7187.425005999612;7285.927140728525;5763.279590051244;7047.365737083482;7058.364747172779;7085.39774119185;7089.964550177217;6211.223597050369;6339.1125242467015;6386;6437.388448097423;6469.838254043499;4788.066327066189;4873.561379475864;4882.487338829372;4957.405111386656;5034.622403319692;4936.234883593049;5046.59627229828;5078.08594453002;5141.7686204120155;5236.371635403776 02:28:01 INFO - PROCESS | 1644 | |4;prototype.html;281.91965289892386;288.60893489321967;335.34272825262485;337.93748156591016;376.3113502290808;5369.194620806881;5472.716370177891;5483.479069488392;5487.6158668893195;5512.2834031575885;4773.618110551156;5052.418971818242;5350.592233494924;5375.489328513785;5390.730463476821;3964.504436945389;4009.8396064157546;4125.690573207006;4130.8967275818195;4193.993441574015;4127.163412281536;4141.378793181032;4162.687798415116;4201.831926722927;4224.8521301754445;4151.647109995654;4172.666186705072;4175.91648167038;4182.602652747984;4228.915421691564;3759.661630453272;4004.6395824375654;4076.7553946763287;4162.522304581876;4211.957880421187;2835.9790475429036;3379.239671074013;3478.3217272631846;3490.3193877193958;3500.5274287971306;4509.6392288616835;4523.841665541723;4934.69230653877;5253.369595648546;5308.628396012281;3830.525247779625;4377.518472967971;4419.690621656486;4644.790984405676;4683.461401938786;4703.2004559225015;4944.33251511045;5259.053370393328;5651.717414129277;5710.143478478261;3202.9359412811614;3364.394409006378;3481.657608695655;3846.576876543578;3890.8716472223023;5763.4812866842185;5913.615614985012;5954.404559544054;6020.6387616742695;6053.455189032964;3364.158960259935;3654.7076233901225;3800.3539398302355;3947.822347994347;3950.7555120137063;3539.4690796380232;3690.28039532294;3693.445983102556;3909.9247706880838;4012.598740125964;4065.2682517147196;4067.877963661095;4546.4544254689645;4646.883827904262;4697.78859951303;4956.801727930842;5577.191931296136;5584.274044374205;5722.284714410699;5765.04876695349;1232.5131573028602;1299.3243513373181;1301.4859130643342;1302.8274552902465;1309.2275557421167;811.3630799822112;833.6832003838502;876.9429987050821;888.2449917570013;888.4580405952493;4602.746848923274;4886.902261954741;4922.867740419651;4936.456989731127;5330.466953304638;5741.569382296345;5791.420857914259;5826.621269617462;5830.47525722687;5877.500412464903;4427.184854209823;4559.863204103882;5011.774470148852;5294.205869119586;5350;2570.884310206045;2584.7851509790366;2924.707529247101;2953.0550223928567;2964.643108728566;4791.664583479123;5092.567131793839;5285.735713214401;5680.75610134003;5824.737886795105;4764.785584648699;4876.780544875489;5510.641808282529;5737.598368114275;5763.164341170507;5761.423857614289;6060.303065147476;6062.484688801501;6065.877812604771;6084.209052823105;4879.926801098058;5687.516561092353;5865.917599252204;5883.5577091189625;5931.936623412976;5742.7702891883855;5787.6816775076095;5803.562503748351;5822.505087067647;5826.679532625746;5196.298499702643;5414.944085903211;5904.645721256738;6085.939140608714;6109.725062372115;3306.851191696331;3318.867245310257;3563.875264365735;3826.368649172967;3902.024493876531;5803.506701930383;5823;5833.1250312451775;5861.208736820475;5866.560007999503;4294.549072347359;5602.386460732886;5725.770969161187;5729.138030140138;5747.9594743867;4988.775505102207;5341.465853414783;5423.538999185113;5605.887882242414;5671.914921276101;4964.478729733326;5614.6631202128;5620.803271885465;5657.971710141423;5672.971635141798;5153.2270159476375;5246.213068039825;5566.8866226754;5573.8606534835335;5742.626729262584;4520.615747661354;4857.684250523705;5015.548600625962;5574.554035677055;5595.160725891149 02:28:01 INFO - PROCESS | 1644 | |5;yui.html;633.2812258087072;635.1806170040647;667.4626925325113;684.8116767888833;744.0661969228618;14152.929235353822;14287.857121428782;14317.856821431782;14359.856401435982;14525.782113268297;13820.170789752621;13820.308984550757;14333.56999290023;14504.564863054125;14568.053076550043;863.1454859688894;868.1015149320455;877.4779006491159;877.7761670773943;879.4986857491233;871.6992637540038;872.0233338660709;872.9214370706635;874.7638137702817;879.7272845417909;882.8434750476896;907.5280853955938;918.1828172926101;918.660095764568;919.8114386550741;803.1927912447982;900.7838118851494;911.8404279251171;912.2656261709321;913.1690161952624;670.6914819183181;670.9832254193684;684.6576711644178;688.4458011300901;690.8894576867727;13662.11196272249;13804.93097534516;13831.79252311216;13846.653833654138;14021.64945876351;11871.643850684493;12221.694457638541;12460;13167.802482962763;13316.001299902546;12717.427715752814;13034.739305213938;13108.80336794949;13199.472021119143;13239.669008274774;4377.868663940087;4450.554944505556;4499.0776890737325;4579.358889755437;4706.317583950342;92212;92727.07272927252;97355;105443;107374.46312768386;10832.67501974942;11057;11122.944385278184;11237;11537.250078744855;22137.225197118023;22172.445688857584;22233.44416389603;22407.551848963256;22424.215152469584;6751.257361690308;7817.179196184433;7863.174366691529;7898.131205567383;7932;3925.7840818755244;4124.670026397881;4254.7021708480725;4285.228658841376;4331.7834108294455;3483.303339332093;3593.82030898454;3596.9100772480897;3596.9568825040446;3609.9458508122398;660.3330636057616;663.2637772072995;675.9628220447825;689.8344397344601;694.3334398977039;667.1427216027381;689.6172624193521;692.747147291235;699.2797418658789;703.3985942019602;8412.411131220755;10258.230632702576;10339.9483002586;10512;10592.205584581034;658.233158370493;691.8962155676688;714.4498735973272;715.7530651925077;721.4769292263068;4797.784099715452;4994.375703037121;5028.5977121830165;5035.420926593489;5046.9747651261505;94736.0526394741;103176.48411757893;103953.48023259986;104489;109530;84661;87203.25593488094;88847;91271;91546.54226728824;60259.397406025375;77085.22914770893;77244;78856;79251.8112228317;9439.150476457151;9598.616055357987;9664;9676.70969870905;9720.854187187339;47568.762156189;48659;53237;53627.46372536381;54542.63643408788;70059.59880802457;70287.9456808138;71233.28766712474;72978;74210.62894685492;13019.804702929274;13109.672258193621;13388.866111338762;13424.127431716906;13698.315084245549;14224.502142424966;14495.492657756928;14966.775498367315;15017.549473515812;15148.469803556822;14019.228942407857;14329.785053224;14504.637384065061;14508.056976296508;14513.346899389764;13763.243021633907;13966.231857247543;14456.710865782836;14476.348564314421;14518.637034074232;13499.86500134986;13578.932105339805;13913.930430347784;14052.227127508086;14081.155130692192;11189.496472658588;11221.60724374643;11626;11883.989860861924;11941.76116477683;8091.312238459797;9128.954355228181;11091.500882460146;11139.777204455704;11578.536858525564 02:28:01 INFO - PROCESS | 1644 | __end_tp_report 02:28:01 INFO - PROCESS | 1644 | __start_cc_report 02:28:01 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,374 02:28:01 INFO - PROCESS | 1644 | __end_cc_report 02:28:01 INFO - PROCESS | 1644 | __startTimestamp1485685681189__endTimestamp 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | ------- Summary: start ------- 02:28:01 INFO - PROCESS | 1644 | Number of tests: 6 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5703.94 Median:5910.44 stddev:659.93 (11.2%) stddev-sans-first:626.50 02:28:01 INFO - PROCESS | 1644 | Values: 2983.7 3364.7 3382.2 3396.8 3397.3 4655.4 5662.5 5663.1 5689.4 5766.5 5721.0 5791.9 5802.6 5805.0 5825.0 5204.3 5264.1 5279.6 5288.3 5330.1 5336.1 5348.8 5365.5 5387.5 5393.5 5190.1 5214.2 5332.5 5333.8 5336.1 5252.0 5259.4 5278.9 5331.3 5360.3 4327.8 5169.4 5254.9 5255.5 5327.2 4295.7 5212.9 5247.7 5794.8 5802.2 4638.8 5921.4 6109.9 6116.2 6239.7 6248.4 6249.1 6257.4 6266.4 6267.5 5809.8 5824.1 5830.1 5833.5 5877.2 6193.8 6217.6 6235.5 6266.8 6274.2 6126.6 6132.1 6165.3 6168.5 6180.1 6225.8 6267.7 6286.0 6290.3 6295.5 5120.0 6037.5 6119.0 6128.1 6132.9 5496.6 6220.3 6229.8 6232.7 6237.6 4986.2 4995.1 4996.3 4999.8 5008.1 4410.9 4418.3 4427.3 4492.0 4512.0 5714.3 5716.6 5786.8 5815.1 5826.3 6214.0 6236.1 6238.3 6250.7 6253.2 5656.7 5709.3 5739.4 5774.5 5774.9 5473.1 5490.0 5504.9 5507.0 5510.9 6315.2 6319.8 6340.2 6347.0 6360.7 6113.3 6172.1 6185.3 6198.2 6290.2 6252.2 6276.6 6288.4 6289.0 6298.6 6174.0 6182.2 6200.1 6207.3 6225.0 6105.9 6152.0 6179.7 6264.1 6277.5 6052.7 6119.7 6138.6 6140.7 6144.0 4861.4 6082.9 6117.5 6134.1 6137.8 4877.0 5810.9 5920.2 6006.6 6025.9 5965.5 5996.5 6025.0 6033.3 6086.2 5901.3 5910.4 5910.4 5911.6 5935.7 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:67679.71 Median:37043.44 stddev:144860.81 (391.1%) stddev-sans-first:145179.20 02:28:01 INFO - PROCESS | 1644 | Values: 433.5 445.4 477.4 505.3 536.7 38628.2 42208.6 42478.9 42505.8 43070.1 39737.6 43270.9 43868.3 43870.3 43872.3 8374.0 8583.3 8812.9 9478.3 9480.8 11045.7 11411.3 11424.7 11573.0 11584.0 9732.9 11396.5 11478.8 11495.2 11498.0 11440.1 11485.0 11498.8 11504.9 11532.3 11353.8 11415.5 11426.5 11429.0 11433.2 41809.0 41819.6 42124.4 42184.6 42194.4 4033.3 4122.0 4127.9 4192.6 4270.6 30553.5 30576.9 31781.5 31824.4 32007.5 3158.0 3186.3 3281.3 3286.8 3297.5 381753.0 385773.1 386170.0 386892.0 387631.1 66677.7 66914.7 66975.7 67097.3 67224.7 69518.0 70159.3 70254.0 70262.3 70430.0 16778.9 17233.7 17336.7 17377.9 17390.7 11803.4 12051.4 12757.9 13088.8 13201.0 17403.1 18556.6 18769.9 18827.9 18863.3 7068.4 7124.4 7147.8 7211.4 7212.5 829.3 874.2 881.7 906.3 908.1 25869.5 27347.0 27401.5 29139.3 30555.0 947.4 972.5 1048.7 1080.3 1081.1 13804.4 15459.8 15988.0 15993.6 16197.9 642951.0 643952.0 647175.0 650140.0 650233.0 63598.0 63676.0 63729.7 63941.7 63957.7 1423.4 1434.4 1438.3 1453.5 1459.6 29357.4 30209.0 30472.8 30599.7 30692.5 53397.0 53886.7 53955.2 54016.5 54131.7 555551.0 560657.0 562343.0 562753.0 563155.0 21145.3 24838.6 24898.8 25489.7 38143.8 35288.1 43978.8 44407.1 44448.0 44779.3 28657.6 35819.6 36895.1 37745.1 37810.1 36995.4 37091.4 37187.3 37542.2 37938.6 35147.8 35493.6 35510.3 35850.5 35872.6 31681.0 32837.5 33128.0 33466.8 33474.8 24394.0 24627.9 24662.6 24715.6 24788.9 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35964.92 Median:2725.58 stddev:107964.73 (3961.2%) stddev-sans-first:108234.56 02:28:01 INFO - PROCESS | 1644 | Values: 393.3 394.4 396.0 402.5 421.0 16368.6 19129.0 19892.5 20398.9 20500.4 20887.1 20907.7 21455.4 21488.4 21725.7 5190.4 5471.9 5473.9 5505.5 5567.2 5344.8 5553.1 5555.2 5569.9 5585.0 5416.1 5496.8 5541.4 5549.1 5560.6 5458.9 5525.7 5531.1 5576.2 6010.4 5403.7 5414.4 5415.1 5419.5 5425.3 13578.7 18254.0 18479.0 25295.6 26035.2 2508.2 2648.5 2649.8 2659.6 2791.6 9766.0 10150.9 10248.4 10519.7 10662.8 1882.4 1883.1 1883.4 1895.2 1911.7 114656.4 115077.0 120138.0 120792.0 121879.0 9945.2 11049.8 11056.3 11634.8 11976.2 14156.0 14797.4 15604.6 15651.5 15714.2 8541.6 8818.6 8831.0 8875.4 9039.4 7250.5 8693.9 8699.7 8742.8 8886.0 4770.3 4851.3 4856.0 4862.4 4973.8 1582.1 1894.4 2025.7 2056.8 2088.3 957.5 977.3 986.9 989.1 1004.0 13196.5 13464.2 13592.5 13693.5 14035.7 981.7 983.3 984.4 993.6 1000.2 7011.5 7015.3 7100.8 7184.2 7189.0 581345.0 609085.0 630829.0 692630.0 713320.0 129247.0 133110.0 133166.0 138254.6 143295.0 1053.2 1101.5 1111.8 1112.7 1115.2 19205.2 19286.5 20243.1 20331.4 20336.1 31482.0 39365.6 54370.8 57250.4 58419.7 64027.7 82200.0 83478.6 85513.0 86290.6 6087.9 6888.4 7053.4 7256.6 7371.1 8828.3 11933.9 12475.9 12526.7 12994.5 9692.3 10849.2 10875.6 10879.7 11192.9 10626.7 10781.0 10818.8 10832.0 10887.0 10795.5 11030.2 11189.9 11446.4 11503.9 6805.8 7786.0 8099.1 8120.9 8149.8 9413.6 10149.8 10150.6 10358.4 10420.6 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5376.54 Median:5109.93 stddev:2880.74 (56.4%) stddev-sans-first:2873.53 02:28:01 INFO - PROCESS | 1644 | Values: 1426.8 1431.8 1445.6 1460.3 1465.6 7489.3 7725.5 7990.2 8429.7 8438.9 7963.4 8261.0 8266.1 8348.1 8385.1 2306.4 2314.7 2346.0 2347.0 2356.8 2335.3 2356.9 2367.6 2367.7 2372.7 2302.4 2311.6 2346.1 2346.5 2352.1 1839.9 1944.8 2245.0 2292.4 2312.1 2080.7 2256.5 2283.3 2359.4 2369.8 8422.0 8425.4 8449.1 8491.8 8511.9 4672.3 4937.1 5047.3 5192.5 5205.4 3134.9 3176.3 3514.2 3558.4 3572.8 1137.9 1235.8 1726.4 1747.0 1832.4 9434.6 10032.5 10816.5 10896.3 10900.3 4578.3 4756.0 4758.7 4779.3 4813.3 7660.7 8776.1 8785.3 8828.8 8834.4 5056.8 5156.6 5158.6 5162.1 5172.7 4513.2 4661.8 4729.6 4756.9 4815.3 3666.2 3750.5 3762.8 3779.4 3809.1 1166.3 1168.5 1177.5 1178.3 1178.8 803.3 858.3 905.3 924.5 930.6 4821.0 5485.0 5628.8 5680.9 5681.8 969.5 996.6 1014.8 1031.9 1050.8 3701.7 3766.0 3835.1 3861.9 3873.8 9795.6 10114.1 10142.9 10148.1 10152.0 9818.1 9907.3 9940.5 10081.5 10120.9 6555.9 7703.2 7789.0 7976.6 7997.2 5475.5 5515.3 5541.0 5623.1 5631.2 9125.3 9157.9 9229.1 9311.4 9336.4 8065.9 8300.0 8516.6 8823.4 9297.0 5797.8 7199.5 7227.0 7247.4 7296.5 7926.3 7962.7 8060.1 8061.3 8083.8 7137.0 7140.2 7183.6 7187.4 7285.9 5763.3 7047.4 7058.4 7085.4 7090.0 6211.2 6339.1 6386.0 6437.4 6469.8 4788.1 4873.6 4882.5 4957.4 5034.6 4936.2 5046.6 5078.1 5141.8 5236.4 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4530.42 Median:4976.63 stddev:1422.36 (28.6%) stddev-sans-first:1390.14 02:28:01 INFO - PROCESS | 1644 | Values: 281.9 288.6 335.3 337.9 376.3 5369.2 5472.7 5483.5 5487.6 5512.3 4773.6 5052.4 5350.6 5375.5 5390.7 3964.5 4009.8 4125.7 4130.9 4194.0 4127.2 4141.4 4162.7 4201.8 4224.9 4151.6 4172.7 4175.9 4182.6 4228.9 3759.7 4004.6 4076.8 4162.5 4212.0 2836.0 3379.2 3478.3 3490.3 3500.5 4509.6 4523.8 4934.7 5253.4 5308.6 3830.5 4377.5 4419.7 4644.8 4683.5 4703.2 4944.3 5259.1 5651.7 5710.1 3202.9 3364.4 3481.7 3846.6 3890.9 5763.5 5913.6 5954.4 6020.6 6053.5 3364.2 3654.7 3800.4 3947.8 3950.8 3539.5 3690.3 3693.4 3909.9 4012.6 4065.3 4067.9 4546.5 4646.9 4697.8 4956.8 5577.2 5584.3 5722.3 5765.0 1232.5 1299.3 1301.5 1302.8 1309.2 811.4 833.7 876.9 888.2 888.5 4602.7 4886.9 4922.9 4936.5 5330.5 5741.6 5791.4 5826.6 5830.5 5877.5 4427.2 4559.9 5011.8 5294.2 5350.0 2570.9 2584.8 2924.7 2953.1 2964.6 4791.7 5092.6 5285.7 5680.8 5824.7 4764.8 4876.8 5510.6 5737.6 5763.2 5761.4 6060.3 6062.5 6065.9 6084.2 4879.9 5687.5 5865.9 5883.6 5931.9 5742.8 5787.7 5803.6 5822.5 5826.7 5196.3 5414.9 5904.6 6085.9 6109.7 3306.9 3318.9 3563.9 3826.4 3902.0 5803.5 5823.0 5833.1 5861.2 5866.6 4294.5 5602.4 5725.8 5729.1 5748.0 4988.8 5341.5 5423.5 5605.9 5671.9 4964.5 5614.7 5620.8 5658.0 5673.0 5153.2 5246.2 5566.9 5573.9 5742.6 4520.6 4857.7 5015.5 5574.6 5595.2 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20389.89 Median:4539.22 stddev:28930.51 (637.3%) stddev-sans-first:28973.63 02:28:01 INFO - PROCESS | 1644 | Values: 633.3 635.2 667.5 684.8 744.1 14152.9 14287.9 14317.9 14359.9 14525.8 13820.2 13820.3 14333.6 14504.6 14568.1 863.1 868.1 877.5 877.8 879.5 871.7 872.0 872.9 874.8 879.7 882.8 907.5 918.2 918.7 919.8 803.2 900.8 911.8 912.3 913.2 670.7 671.0 684.7 688.4 690.9 13662.1 13804.9 13831.8 13846.7 14021.6 11871.6 12221.7 12460.0 13167.8 13316.0 12717.4 13034.7 13108.8 13199.5 13239.7 4377.9 4450.6 4499.1 4579.4 4706.3 92212.0 92727.1 97355.0 105443.0 107374.5 10832.7 11057.0 11122.9 11237.0 11537.3 22137.2 22172.4 22233.4 22407.6 22424.2 6751.3 7817.2 7863.2 7898.1 7932.0 3925.8 4124.7 4254.7 4285.2 4331.8 3483.3 3593.8 3596.9 3597.0 3609.9 660.3 663.3 676.0 689.8 694.3 667.1 689.6 692.7 699.3 703.4 8412.4 10258.2 10339.9 10512.0 10592.2 658.2 691.9 714.4 715.8 721.5 4797.8 4994.4 5028.6 5035.4 5047.0 94736.1 103176.5 103953.5 104489.0 109530.0 84661.0 87203.3 88847.0 91271.0 91546.5 60259.4 77085.2 77244.0 78856.0 79251.8 9439.2 9598.6 9664.0 9676.7 9720.9 47568.8 48659.0 53237.0 53627.5 54542.6 70059.6 70287.9 71233.3 72978.0 74210.6 13019.8 13109.7 13388.9 13424.1 13698.3 14224.5 14495.5 14966.8 15017.5 15148.5 14019.2 14329.8 14504.6 14508.1 14513.3 13763.2 13966.2 14456.7 14476.3 14518.6 13499.9 13578.9 13913.9 14052.2 14081.2 11189.5 11221.6 11626.0 11884.0 11941.8 8091.3 9129.0 11091.5 11139.8 11578.5 02:28:01 INFO - PROCESS | 1644 | -------- Summary: end -------- 02:28:01 INFO - PROCESS | 1644 | 02:28:01 INFO - TEST-INFO | 1644: exit 0 02:28:01 INFO - TEST-OK | dromaeo_css | took 1114771ms 02:28:01 INFO - TEST-START | kraken 02:28:01 INFO - operating with platform_type : mac_ 02:28:01 INFO - Initialising browser for kraken test... 02:28:01 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmph7WiDd/profile http://localhost:49241/getInfo.html) 02:28:04 INFO - PROCESS | 1652 | 2017-01-29 02:28:04.529 plugin-container[1654:10999] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 02:28:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:04 INFO - PROCESS | 1652 | 2017-01-29 02:28:04.530 plugin-container[1654:10999] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 02:28:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:04 INFO - PROCESS | 1652 | 2017-01-29 02:28:04.530 plugin-container[1654:10999] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:04 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 02:28:04 INFO - PROCESS | 1652 | colorDepth:24 02:28:04 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 02:28:04 INFO - PROCESS | 1652 | __metrics 02:28:04 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 02:28:05 INFO - TEST-INFO | 1652: exit 0 02:28:05 INFO - Browser initialized. 02:28:05 INFO - Running cycle 1/1 for kraken test... 02:28:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmph7WiDd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:07 INFO - PROCESS | 1655 | 2017-01-29 02:28:07.918 plugin-container[1656:11125] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 02:28:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:07 INFO - PROCESS | 1655 | 2017-01-29 02:28:07.918 plugin-container[1656:11125] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 02:28:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:28:07 INFO - PROCESS | 1655 | 2017-01-29 02:28:07.918 plugin-container[1656:11125] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:28:17 INFO - PROCESS | 1655 | RSS: Main: 219320320 02:28:17 INFO - PROCESS | 1655 | 02:28:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:28:21 INFO - PROCESS | 1655 | RSS: Main: 233754624 02:28:21 INFO - PROCESS | 1655 | 02:28:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:28:25 INFO - PROCESS | 1655 | RSS: Main: 242249728 02:28:25 INFO - PROCESS | 1655 | 02:28:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:28:29 INFO - PROCESS | 1655 | RSS: Main: 245387264 02:28:29 INFO - PROCESS | 1655 | 02:28:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:28:31 INFO - PROCESS | 1655 | RSS: Main: 246738944 02:28:31 INFO - PROCESS | 1655 | 02:28:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:28:34 INFO - PROCESS | 1655 | RSS: Main: 247300096 02:28:34 INFO - PROCESS | 1655 | 02:28:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:28:37 INFO - PROCESS | 1655 | RSS: Main: 250765312 02:28:37 INFO - PROCESS | 1655 | 02:28:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:28:41 INFO - PROCESS | 1655 | RSS: Main: 250245120 02:28:41 INFO - PROCESS | 1655 | 02:28:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:28:44 INFO - PROCESS | 1655 | RSS: Main: 250462208 02:28:44 INFO - PROCESS | 1655 | 02:28:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:28:47 INFO - PROCESS | 1655 | RSS: Main: 249380864 02:28:47 INFO - PROCESS | 1655 | 02:28:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:28:49 INFO - PROCESS | 1655 | RSS: Main: 249483264 02:28:49 INFO - PROCESS | 1655 | 02:28:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:28:52 INFO - PROCESS | 1655 | RSS: Main: 249491456 02:28:52 INFO - PROCESS | 1655 | 02:28:55 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:28:56 INFO - PROCESS | 1655 | RSS: Main: 249884672 02:28:56 INFO - PROCESS | 1655 | 02:28:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:00 INFO - PROCESS | 1655 | RSS: Main: 249634816 02:29:00 INFO - PROCESS | 1655 | 02:29:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:29:02 INFO - PROCESS | 1655 | RSS: Main: 251637760 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | __start_tp_report 02:29:02 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 02:29:02 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 02:29:02 INFO - PROCESS | 1655 | |i|pagename|runs| 02:29:02 INFO - PROCESS | 1655 | |0;ai-astar;106;172;174;118;122;104;175;177;163;119 02:29:02 INFO - PROCESS | 1655 | |1;audio-beat-detection;145;116;113;177;114;113;106;112;129;126 02:29:02 INFO - PROCESS | 1655 | |2;audio-dft;196;202;199;202;196;197;201;196;195;196 02:29:02 INFO - PROCESS | 1655 | |3;audio-fft;80;59;61;87;64;61;68;50;74;74 02:29:02 INFO - PROCESS | 1655 | |4;audio-oscillator;82;72;86;73;92;103;83;82;58;68 02:29:02 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;101;111;89;87;106;85;90;87;91;87 02:29:02 INFO - PROCESS | 1655 | |6;imaging-darkroom;103;94;96;98;151;96;97;111;152;153 02:29:02 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;92;94;109;96;92;95;94;88;93 02:29:02 INFO - PROCESS | 1655 | |8;json-parse-financial;61;60;61;63;63;62;64;63;64;61 02:29:02 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;73;71;99;67;100;89;80;64;62;64 02:29:02 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;87;105;81;79;109;104;87;86;78;92 02:29:02 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;140;162;155;157;140;148;175;155;232;180 02:29:02 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;171;172;172;169;176;240;189;169;175;174 02:29:02 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;65;66;67;68;70;78;93;86;72;87 02:29:02 INFO - PROCESS | 1655 | __end_tp_report 02:29:02 INFO - PROCESS | 1655 | __start_cc_report 02:29:02 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,957 02:29:02 INFO - PROCESS | 1655 | __end_cc_report 02:29:02 INFO - PROCESS | 1655 | __startTimestamp1485685742215__endTimestamp 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | ------- Summary: start ------- 02:29:02 INFO - PROCESS | 1655 | Number of tests: 14 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:143.00 Median:167.50 stddev:31.47 (18.8%) stddev-sans-first:30.40 02:29:02 INFO - PROCESS | 1655 | Values: 106.0 172.0 174.0 118.0 122.0 104.0 175.0 177.0 163.0 119.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:121.00 stddev:21.45 (17.7%) stddev-sans-first:21.51 02:29:02 INFO - PROCESS | 1655 | Values: 145.0 116.0 113.0 177.0 114.0 113.0 106.0 112.0 129.0 126.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:198.00 Median:198.00 stddev:2.75 (1.4%) stddev-sans-first:2.82 02:29:02 INFO - PROCESS | 1655 | Values: 196.0 202.0 199.0 202.0 196.0 197.0 201.0 196.0 195.0 196.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:67.80 Median:71.00 stddev:11.03 (15.5%) stddev-sans-first:10.78 02:29:02 INFO - PROCESS | 1655 | Values: 80.0 59.0 61.0 87.0 64.0 61.0 68.0 50.0 74.0 74.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:82.00 stddev:12.77 (15.6%) stddev-sans-first:13.52 02:29:02 INFO - PROCESS | 1655 | Values: 82.0 72.0 86.0 73.0 92.0 103.0 83.0 82.0 58.0 68.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:87.00 stddev:9.17 (10.5%) stddev-sans-first:9.30 02:29:02 INFO - PROCESS | 1655 | Values: 101.0 111.0 89.0 87.0 106.0 85.0 90.0 87.0 91.0 87.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:115.10 Median:95.00 stddev:25.92 (27.3%) stddev-sans-first:27.12 02:29:02 INFO - PROCESS | 1655 | Values: 103.0 94.0 96.0 98.0 151.0 96.0 97.0 111.0 152.0 153.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:93.50 stddev:5.60 (6.0%) stddev-sans-first:5.80 02:29:02 INFO - PROCESS | 1655 | Values: 91.0 92.0 94.0 109.0 96.0 92.0 95.0 94.0 88.0 93.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:63.00 stddev:1.40 (2.2%) stddev-sans-first:1.41 02:29:02 INFO - PROCESS | 1655 | Values: 61.0 60.0 61.0 63.0 63.0 62.0 64.0 63.0 64.0 61.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:76.90 Median:72.00 stddev:14.46 (20.1%) stddev-sans-first:15.26 02:29:02 INFO - PROCESS | 1655 | Values: 73.0 71.0 99.0 67.0 100.0 89.0 80.0 64.0 62.0 64.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:83.50 stddev:11.35 (13.6%) stddev-sans-first:11.96 02:29:02 INFO - PROCESS | 1655 | Values: 87.0 105.0 81.0 79.0 109.0 104.0 87.0 86.0 78.0 92.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:164.40 Median:159.50 stddev:27.13 (17.0%) stddev-sans-first:27.30 02:29:02 INFO - PROCESS | 1655 | Values: 140.0 162.0 155.0 157.0 140.0 148.0 175.0 155.0 232.0 180.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:174.50 stddev:21.61 (12.4%) stddev-sans-first:22.64 02:29:02 INFO - PROCESS | 1655 | Values: 171.0 172.0 172.0 169.0 176.0 240.0 189.0 169.0 175.0 174.0 02:29:02 INFO - PROCESS | 1655 | 02:29:02 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:75.00 stddev:10.14 (13.5%) stddev-sans-first:10.06 02:29:02 INFO - PROCESS | 1655 | Values: 65.0 66.0 67.0 68.0 70.0 78.0 93.0 86.0 72.0 87.0 02:29:02 INFO - PROCESS | 1655 | -------- Summary: end -------- 02:29:02 INFO - PROCESS | 1655 | 02:29:03 INFO - TEST-INFO | 1655: exit 0 02:29:03 INFO - TEST-OK | kraken | took 61268ms 02:29:03 INFO - SUITE-END | took 1176s 02:29:03 INFO - Completed test suite (00:19:36) 02:29:03 INFO - javascript benchmark 02:29:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7250.3563870086955, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2983.656879458862, 3364.747643926706, 3382.238996225848, 3396.7961922284667, 3397.3205358928203, 4655.394798676176, 5662.546996240301, 5663.065594176961, 5689.374168841429, 5766.481016708506, 5720.970225359433, 5791.942080579193, 5802.622829516078, 5805.0, 5824.970875145618, 5204.32343795306, 5264.131418315973, 5279.604029697788, 5288.286081379003, 5330.1205301125265, 5336.066188417031, 5348.759305831228, 5365.543928766059, 5387.542058924996, 5393.541548968343, 5190.143626301656, 5214.217867319913, 5332.466753324676, 5333.813316533922, 5336.092864213074, 5252.028374750684, 5259.447757985414, 5278.868028299285, 5331.253624492574, 5360.303160589098, 4327.848525301631, 5169.4388503517985, 5254.894902101936, 5255.474452554752, 5327.227552004976, 4295.677824163169, 5212.869678258036, 5247.661846229225, 5794.82615521535, 5802.245708057968, 4638.768061596907, 5921.437463440966, 6109.890546867989, 6116.244470650298, 6239.719212635442, 6248.3751624837605, 6249.0626406039, 6257.43683068526, 6266.40469155434, 6267.4986001119805, 5809.82570522885, 5824.097264923944, 5830.067189249699, 5833.474987251126, 5877.206577112078, 6193.81418557444, 6217.595856269328, 6235.501159907197, 6266.843328916813, 6274.247090349188, 6126.6017708848785, 6132.127275087106, 6165.260168779775, 6168.537359698041, 6180.134781130645, 6225.844353891099, 6267.65527895961, 6285.96857015712, 6290.308066112724, 6295.527835412271, 5119.97440012805, 6037.45662890342, 6119.045604113885, 6128.142060111588, 6132.938670613326, 5496.587755918322, 6220.25356957159, 6229.844253893689, 6232.657203853832, 6237.594556353823, 4986.227134794112, 4995.0509403214, 4996.300517927493, 4999.825006124768, 5008.1486147355035, 4410.867673969786, 4418.315161150027, 4427.269500532376, 4492.0, 4512.029913568598, 5714.257146570919, 5716.628419152742, 5786.826395208151, 5815.098659707752, 5826.300843898759, 6213.96893015532, 6236.126942228092, 6238.251409830849, 6250.74997000124, 6253.155823963798, 5656.688882111441, 5709.286339207599, 5739.397363276879, 5774.509166720875, 5774.884502310014, 5473.06957817172, 5490.039243132515, 5504.889902201933, 5507.0, 5510.9448905511235, 6315.242170939517, 6319.842003949938, 6340.239171299474, 6347.0, 6360.713767880364, 6113.293877754158, 6172.105044768573, 6185.2886918003205, 6198.194234749454, 6290.1508296381235, 6252.249730032425, 6276.592021518587, 6288.402601752826, 6288.968555157195, 6298.559100862895, 6174.043023331391, 6182.227221597301, 6200.100985357008, 6207.34822843613, 6225.0, 6105.908411373922, 6151.96924015377, 6179.721912514037, 6264.123022776906, 6277.466415354746, 6052.66710330936, 6119.68535298207, 6138.633639632548, 6140.657627863787, 6143.972489078683, 4861.392325959255, 6082.878342433038, 6117.541184411276, 6134.14122022926, 6137.815865524041, 4876.975835074674, 5810.912836307543, 5920.200772895776, 6006.639601623917, 6025.909611355745, 5965.463108320272, 5996.490298324691, 6025.005874030736, 6033.276006879202, 6086.239220097488, 5901.3213480448585, 5910.438508341701, 5910.438508341701, 5911.586188966731, 5935.732892019956], "value": 5663.779610754192, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.51446380054324, 445.4031597659137, 477.4175505882824, 505.32286735774056, 536.6994483089472, 38628.22743545128, 42208.577914220885, 42478.93802654935, 42505.78747106268, 43070.13859722808, 39737.602623973755, 43270.91822704426, 43868.34197487032, 43870.34194487077, 43872.3419148713, 8373.995120585538, 8583.313334933222, 8812.8678069829, 9478.28912831537, 9480.81038379232, 11045.723856903562, 11411.25826821254, 11424.714382140472, 11573.0, 11584.015358694522, 9732.854007189897, 11396.487158077907, 11478.770424591503, 11495.19533632646, 11498.0, 11440.14198935079, 11485.0, 11498.770024599546, 11504.942475287653, 11532.308061516294, 11353.772924541463, 11415.54337826486, 11426.485808138654, 11429.02853257474, 11433.199676022681, 41808.95477613054, 41819.58180418187, 42124.368134478005, 42184.57815421868, 42194.36708449376, 4033.2740106780766, 4122.0, 4127.855525056609, 4192.559781222958, 4270.636995855356, 30553.541696874563, 30576.929807456854, 31781.52327715086, 31824.363512729615, 32007.51988720171, 3157.952630710541, 3186.314942287396, 3281.3109247058146, 3286.753493487974, 3297.505374193866, 381753.0, 385773.14226857654, 386170.0, 386892.0, 387631.0618446918, 66677.6666116676, 66914.66170676559, 66975.66512167409, 67097.3290267091, 67224.66387668031, 69518.0, 70159.29840701527, 70254.0, 70262.2973770266, 70430.0, 16778.90937089085, 17233.655326893393, 17336.65326693459, 17377.91311043462, 17390.65218695619, 11803.40982950849, 12051.397430128458, 12757.872421275853, 13088.803667944989, 13201.0, 17403.129843507773, 18556.628867422576, 18769.90615046916, 18827.905860470884, 18863.3397831078, 7068.363847253669, 7124.358807707227, 7147.820609599462, 7211.387032102225, 7212.4951253411755, 829.2909562324246, 874.208840998897, 881.7398867334152, 906.2885634776669, 908.1236606674458, 25869.482610347688, 27347.04285350043, 27401.492917889707, 29139.27151821179, 30555.0, 947.3657940053353, 972.4845831709206, 1048.7011201807447, 1080.330195278932, 1081.1134869407167, 13804.447822087204, 15459.845401545841, 15988.040717556984, 15993.60015999586, 16197.94713343629, 642951.0, 643952.0, 647175.0, 650140.0, 650233.0, 63598.0, 63676.0, 63729.725405491634, 63941.68029159824, 63957.680211599574, 1423.3808293392408, 1434.400643282753, 1438.2736717957573, 1453.5130731205165, 1459.6350912271719, 29357.412851743273, 30209.0, 30472.84763576168, 30599.694003060577, 30692.539611906286, 53397.0, 53886.73056634849, 53955.190672140736, 54016.45983540272, 54131.72934135304, 555551.0, 560657.0, 562343.0, 562753.0, 563155.0, 21145.25991590287, 24838.627420588346, 24898.751012490367, 25489.745102548735, 38143.80928095342, 35288.11779705528, 43978.780106099264, 44407.111857762015, 44448.0, 44779.32831007472, 28657.570136447554, 35819.64180358163, 36895.07762305856, 37745.05637359088, 37810.054748631504, 36995.44506832454, 37091.44362834613, 37187.25625487529, 37542.24915501729, 37938.62061379351, 35147.82426087853, 35493.645063550066, 35510.289794203454, 35850.46224306586, 35872.64127358797, 31681.0, 32837.50743738894, 33128.0, 33466.83266583652, 33474.832625836716, 24394.02423903024, 24627.876860615583, 24662.64355460395, 24715.62926556067, 24788.876055620327], "value": 20264.888718514365, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.3097414038362, 394.4379259555133, 395.9524857017158, 402.47276068350465, 421.0001247038284, 16368.590785230346, 19129.0, 19892.50268743282, 20398.89800550999, 20500.38498845035, 20887.060082296295, 20907.68638470424, 21455.356339309845, 21488.355349339505, 21725.67411488829, 5190.377154741436, 5471.917921231185, 5473.917891231635, 5505.476979686923, 5567.220589117527, 5344.7862085516535, 5553.055980483307, 5555.194496797982, 5569.916451253234, 5585.0, 5416.1063424534905, 5496.835094947158, 5541.445855414447, 5549.112142057272, 5560.555155587544, 5458.890822183574, 5525.696086715229, 5531.0873705838485, 5576.247206627094, 6010.398960103998, 5403.729813509309, 5414.35027796663, 5415.079436495806, 5419.512243898068, 5425.267588875491, 13578.660533486742, 18253.99603021833, 18479.0, 25295.62056569153, 26035.218943431537, 2508.209913877141, 2648.523265812172, 2649.774769144625, 2659.587763896599, 2791.567307067408, 9765.951170244174, 10150.949245253727, 10248.436336001518, 10519.684409467729, 10662.840057399146, 1882.4446788197515, 1883.1243471785685, 1883.4349695091419, 1895.2229585869727, 1911.7419148415065, 114656.42671786588, 115077.0, 120138.0, 120792.0, 121878.99999999911, 9945.154661853823, 11049.834252486218, 11056.336619802676, 11634.825477617842, 11976.221545599508, 14156.0, 14797.408103675732, 15604.609884752745, 15651.530454086396, 15714.21428928549, 8541.615627296787, 8818.647254109765, 8830.955845220862, 8875.378723489293, 9039.367244292836, 7250.492465527468, 8693.91306086931, 8699.695510657097, 8742.781430464289, 8885.955570222237, 4770.332153498513, 4851.343384148901, 4856.0, 4862.358909195588, 4973.776180071905, 1582.1219223331161, 1894.4316704988448, 2025.6961455781457, 2056.7737548869613, 2088.3421722157777, 957.4686049242598, 977.2524019125375, 986.8798913233388, 989.1493315748451, 1004.0311099788739, 13196.538121165713, 13464.249647545756, 13592.45630174782, 13693.452261909611, 14035.71928561423, 981.7202097402206, 983.2674657380297, 984.4437892590663, 993.6224234790733, 1000.2198285337448, 7011.5091943564485, 7015.298470153046, 7100.751473698396, 7184.209736928934, 7189.0, 581345.0, 609085.0, 630829.0, 692630.0, 713320.0, 129247.0, 133110.0, 133166.0, 138254.61745382618, 143295.0, 1053.2416660004785, 1101.5208384352673, 1111.8054340490544, 1112.6940091474746, 1115.2360632966445, 19205.231790728772, 19286.517837054187, 20243.089060992585, 20331.390058298275, 20336.08487618031, 31482.0, 39365.60634393734, 54370.81257729112, 57250.42749572451, 58419.70790146022, 64027.679861600394, 82200.0, 83478.58260708657, 85513.0, 86290.56854715938, 6087.8782424350375, 6888.448924086161, 7053.4004609608755, 7256.637168141678, 7371.115466144097, 8828.293736501193, 11933.940330298294, 12475.875241247471, 12526.749465010467, 12994.480220791062, 9692.273079519206, 10849.240553161202, 10875.564977400816, 10879.728006799893, 11192.88807111951, 10626.73433164177, 10781.02970732638, 10818.837717434402, 10832.025117739442, 10886.96573825517, 10795.460226988776, 11030.172737044593, 11189.88810111921, 11446.37044962535, 11503.907128822748, 6805.761798337035, 7786.0, 8099.14958929304, 8120.878186827084, 8149.8182763500145, 9413.623455061721, 10149.797004060025, 10150.593976240867, 10358.378497290187, 10420.635277765241], "value": 9289.06452706513, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.7574512332903, 1431.798436142485, 1445.624137724192, 1460.277162804412, 1465.5530063330687, 7489.325960663539, 7725.497842640239, 7990.161033091522, 8429.747107586767, 8438.915610843897, 7963.442559020871, 8260.958695206531, 8266.090730019692, 8348.12344703805, 8385.077641459435, 2306.4464528513163, 2314.687517185184, 2345.9765402346015, 2346.9530609387803, 2356.846804957681, 2335.322756400642, 2356.9410764730933, 2367.621180611103, 2367.6803631509783, 2372.738998710149, 2302.366849116498, 2311.5783792967286, 2346.061575369857, 2346.495503466754, 2352.1179557665873, 1839.8896066236068, 1944.8249657530748, 2244.9887750561224, 2292.3695983604644, 2312.144506532594, 2080.6566916458764, 2256.4584499720113, 2283.2693538067824, 2359.3865594945437, 2369.822263330257, 8421.989361276625, 8425.368097392658, 8449.112843151504, 8491.787705307355, 8511.872321915176, 4672.322513235562, 4937.111319962405, 5047.3438453001245, 5192.480751924815, 5205.375354957429, 3134.9216269593217, 3176.28533579945, 3514.1917359007316, 3558.430651095812, 3572.7677700949357, 1137.8919002694731, 1235.8269842222096, 1726.413019573351, 1746.97379539307, 1832.4411054628333, 9434.622615095388, 10032.49837508129, 10816.459177041195, 10896.346219226873, 10900.291481053706, 4578.313253012008, 4755.95244047562, 4758.714477131383, 4779.259214821708, 4813.278008298713, 7660.659384607671, 8776.122387761301, 8785.253253473526, 8828.823423531494, 8834.425762325429, 5056.797728090909, 5156.607715916756, 5158.638895277295, 5162.1224391853475, 5172.663776854492, 4513.210188217049, 4661.836835710734, 4729.645276604199, 4756.857294281177, 4815.253635686474, 3666.175110600094, 3750.4936833527127, 3762.811859407019, 3779.3764028935466, 3809.12390150267, 1166.2710805746408, 1168.5209064283604, 1177.4936777185721, 1178.269472926791, 1178.776032553812, 803.3087960820022, 858.3176374782063, 905.3391024552113, 924.5469719837231, 930.5905401623264, 4820.975895120502, 5484.985277723634, 5628.774849005994, 5680.857978550486, 5681.801136960186, 969.4910172159673, 996.5599708421262, 1014.8071866345371, 1031.9432431216205, 1050.8056009638242, 3701.6668499835146, 3765.9623403765613, 3835.060410199519, 3861.8841434757, 3873.845046198177, 9795.608175673036, 10114.140298074599, 10142.949285253526, 10148.137408320375, 10152.03555662211, 9818.067283608047, 9907.343189449934, 9940.453275069796, 10081.546330415149, 10120.898791012143, 6555.868882622321, 7703.1911649277135, 7789.0, 7976.601169941479, 7997.240262175084, 5475.534579560782, 5515.338159420895, 5540.972295138498, 5623.072193088258, 5631.239782629456, 9125.269978401844, 9157.908420915972, 9229.123233292827, 9311.394759340621, 9336.439813611205, 8065.919340806517, 8299.95850020746, 8516.574171291535, 8823.411785878603, 9297.02381249959, 5797.797077102281, 7199.532030418006, 7227.0, 7247.420206383371, 7296.489245752747, 7926.326262267772, 7962.681492740226, 8060.1133875274845, 8061.314788243065, 8083.797905052421, 7136.964315178391, 7140.178879428932, 7183.640817958976, 7187.425005999612, 7285.927140728525, 5763.279590051244, 7047.365737083482, 7058.364747172779, 7085.39774119185, 7089.964550177217, 6211.223597050369, 6339.1125242467015, 6386.0, 6437.388448097423, 6469.838254043499, 4788.066327066189, 4873.561379475864, 4882.487338829372, 4957.405111386656, 5034.622403319692, 4936.234883593049, 5046.59627229828, 5078.08594453002, 5141.7686204120155, 5236.371635403776], "value": 4411.81028085068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.91965289892386, 288.60893489321967, 335.34272825262485, 337.93748156591016, 376.3113502290808, 5369.194620806881, 5472.716370177891, 5483.479069488392, 5487.6158668893195, 5512.2834031575885, 4773.618110551156, 5052.418971818242, 5350.592233494924, 5375.489328513785, 5390.730463476821, 3964.504436945389, 4009.8396064157546, 4125.690573207006, 4130.8967275818195, 4193.993441574015, 4127.163412281536, 4141.378793181032, 4162.687798415116, 4201.831926722927, 4224.8521301754445, 4151.647109995654, 4172.666186705072, 4175.91648167038, 4182.602652747984, 4228.915421691564, 3759.661630453272, 4004.6395824375654, 4076.7553946763287, 4162.522304581876, 4211.957880421187, 2835.9790475429036, 3379.239671074013, 3478.3217272631846, 3490.3193877193958, 3500.5274287971306, 4509.6392288616835, 4523.841665541723, 4934.69230653877, 5253.369595648546, 5308.628396012281, 3830.525247779625, 4377.518472967971, 4419.690621656486, 4644.790984405676, 4683.461401938786, 4703.2004559225015, 4944.33251511045, 5259.053370393328, 5651.717414129277, 5710.143478478261, 3202.9359412811614, 3364.394409006378, 3481.657608695655, 3846.576876543578, 3890.8716472223023, 5763.4812866842185, 5913.615614985012, 5954.404559544054, 6020.6387616742695, 6053.455189032964, 3364.158960259935, 3654.7076233901225, 3800.3539398302355, 3947.822347994347, 3950.7555120137063, 3539.4690796380232, 3690.28039532294, 3693.445983102556, 3909.9247706880838, 4012.598740125964, 4065.2682517147196, 4067.877963661095, 4546.4544254689645, 4646.883827904262, 4697.78859951303, 4956.801727930842, 5577.191931296136, 5584.274044374205, 5722.284714410699, 5765.04876695349, 1232.5131573028602, 1299.3243513373181, 1301.4859130643342, 1302.8274552902465, 1309.2275557421167, 811.3630799822112, 833.6832003838502, 876.9429987050821, 888.2449917570013, 888.4580405952493, 4602.746848923274, 4886.902261954741, 4922.867740419651, 4936.456989731127, 5330.466953304638, 5741.569382296345, 5791.420857914259, 5826.621269617462, 5830.47525722687, 5877.500412464903, 4427.184854209823, 4559.863204103882, 5011.774470148852, 5294.205869119586, 5350.0, 2570.884310206045, 2584.7851509790366, 2924.707529247101, 2953.0550223928567, 2964.643108728566, 4791.664583479123, 5092.567131793839, 5285.735713214401, 5680.75610134003, 5824.737886795105, 4764.785584648699, 4876.780544875489, 5510.641808282529, 5737.598368114275, 5763.164341170507, 5761.423857614289, 6060.303065147476, 6062.484688801501, 6065.877812604771, 6084.209052823105, 4879.926801098058, 5687.516561092353, 5865.917599252204, 5883.5577091189625, 5931.936623412976, 5742.7702891883855, 5787.6816775076095, 5803.562503748351, 5822.505087067647, 5826.679532625746, 5196.298499702643, 5414.944085903211, 5904.645721256738, 6085.939140608714, 6109.725062372115, 3306.851191696331, 3318.867245310257, 3563.875264365735, 3826.368649172967, 3902.024493876531, 5803.506701930383, 5823.0, 5833.1250312451775, 5861.208736820475, 5866.560007999503, 4294.549072347359, 5602.386460732886, 5725.770969161187, 5729.138030140138, 5747.9594743867, 4988.775505102207, 5341.465853414783, 5423.538999185113, 5605.887882242414, 5671.914921276101, 4964.478729733326, 5614.6631202128, 5620.803271885465, 5657.971710141423, 5672.971635141798, 5153.2270159476375, 5246.213068039825, 5566.8866226754, 5573.8606534835335, 5742.626729262584, 4520.615747661354, 4857.684250523705, 5015.548600625962, 5574.554035677055, 5595.160725891149], "value": 4080.2794188728385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.2812258087072, 635.1806170040647, 667.4626925325113, 684.8116767888833, 744.0661969228618, 14152.929235353822, 14287.857121428782, 14317.856821431782, 14359.856401435982, 14525.782113268297, 13820.170789752621, 13820.308984550757, 14333.56999290023, 14504.564863054125, 14568.053076550043, 863.1454859688894, 868.1015149320455, 877.4779006491159, 877.7761670773943, 879.4986857491233, 871.6992637540038, 872.0233338660709, 872.9214370706635, 874.7638137702817, 879.7272845417909, 882.8434750476896, 907.5280853955938, 918.1828172926101, 918.660095764568, 919.8114386550741, 803.1927912447982, 900.7838118851494, 911.8404279251171, 912.2656261709321, 913.1690161952624, 670.6914819183181, 670.9832254193684, 684.6576711644178, 688.4458011300901, 690.8894576867727, 13662.11196272249, 13804.93097534516, 13831.79252311216, 13846.653833654138, 14021.64945876351, 11871.643850684493, 12221.694457638541, 12460.0, 13167.802482962763, 13316.001299902546, 12717.427715752814, 13034.739305213938, 13108.80336794949, 13199.472021119143, 13239.669008274774, 4377.868663940087, 4450.554944505556, 4499.0776890737325, 4579.358889755437, 4706.317583950342, 92212.0, 92727.07272927252, 97355.0, 105443.0, 107374.46312768386, 10832.67501974942, 11057.0, 11122.944385278184, 11237.0, 11537.250078744855, 22137.225197118023, 22172.445688857584, 22233.44416389603, 22407.551848963256, 22424.215152469584, 6751.257361690308, 7817.179196184433, 7863.174366691529, 7898.131205567383, 7932.0, 3925.7840818755244, 4124.670026397881, 4254.7021708480725, 4285.228658841376, 4331.7834108294455, 3483.303339332093, 3593.82030898454, 3596.9100772480897, 3596.9568825040446, 3609.9458508122398, 660.3330636057616, 663.2637772072995, 675.9628220447825, 689.8344397344601, 694.3334398977039, 667.1427216027381, 689.6172624193521, 692.747147291235, 699.2797418658789, 703.3985942019602, 8412.411131220755, 10258.230632702576, 10339.9483002586, 10512.0, 10592.205584581034, 658.233158370493, 691.8962155676688, 714.4498735973272, 715.7530651925077, 721.4769292263068, 4797.784099715452, 4994.375703037121, 5028.5977121830165, 5035.420926593489, 5046.9747651261505, 94736.0526394741, 103176.48411757893, 103953.48023259986, 104489.0, 109530.0, 84661.0, 87203.25593488094, 88847.0, 91271.0, 91546.54226728824, 60259.397406025375, 77085.22914770893, 77244.0, 78856.0, 79251.8112228317, 9439.150476457151, 9598.616055357987, 9664.0, 9676.70969870905, 9720.854187187339, 47568.762156189, 48659.0, 53237.0, 53627.46372536381, 54542.63643408788, 70059.59880802457, 70287.9456808138, 71233.28766712474, 72978.0, 74210.62894685492, 13019.804702929274, 13109.672258193621, 13388.866111338762, 13424.127431716906, 13698.315084245549, 14224.502142424966, 14495.492657756928, 14966.775498367315, 15017.549473515812, 15148.469803556822, 14019.228942407857, 14329.785053224, 14504.637384065061, 14508.056976296508, 14513.346899389764, 13763.243021633907, 13966.231857247543, 14456.710865782836, 14476.348564314421, 14518.637034074232, 13499.86500134986, 13578.932105339805, 13913.930430347784, 14052.227127508086, 14081.155130692192, 11189.496472658588, 11221.60724374643, 11626.0, 11883.989860861924, 11941.76116477683, 8091.312238459797, 9128.954355228181, 11091.500882460146, 11139.777204455704, 11578.536858525564], "value": 7567.965286046523, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 172.0, 174.0, 118.0, 122.0, 104.0, 175.0, 177.0, 163.0, 119.0], "value": 143.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 113.0, 177.0, 114.0, 113.0, 106.0, 112.0, 129.0, 126.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 199.0, 202.0, 196.0, 197.0, 201.0, 196.0, 195.0, 196.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 61.0, 87.0, 64.0, 61.0, 68.0, 50.0, 74.0, 74.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 86.0, 73.0, 92.0, 103.0, 83.0, 82.0, 58.0, 68.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 89.0, 87.0, 106.0, 85.0, 90.0, 87.0, 91.0, 87.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 94.0, 96.0, 98.0, 151.0, 96.0, 97.0, 111.0, 152.0, 153.0], "value": 115.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 94.0, 109.0, 96.0, 92.0, 95.0, 94.0, 88.0, 93.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 63.0, 63.0, 62.0, 64.0, 63.0, 64.0, 61.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 99.0, 67.0, 100.0, 89.0, 80.0, 64.0, 62.0, 64.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 105.0, 81.0, 79.0, 109.0, 104.0, 87.0, 86.0, 78.0, 92.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 162.0, 155.0, 157.0, 140.0, 148.0, 175.0, 155.0, 232.0, 180.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 172.0, 169.0, 176.0, 240.0, 189.0, 169.0, 175.0, 174.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 68.0, 70.0, 78.0, 93.0, 86.0, 72.0, 87.0], "value": 75.2, "unit": "score"}]}]} 02:29:03 INFO - Return code: 0 02:29:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:03 ERROR - # TBPL SUCCESS # 02:29:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:03 INFO - 2017-01-29 02:29:03.555 screenresolution[1660:11524] starting screenresolution argv=screenresolution get 02:29:03 INFO - 2017-01-29 02:29:03.557 screenresolution[1660:11524] Display 0: 1600x1200x32@60 02:29:03 INFO - 2017-01-29 02:29:03.563 screenresolution[1661:11527] starting screenresolution argv=screenresolution list 02:29:03 INFO - Available Modes on Display 0 02:29:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:03 INFO - Intel Iris: 02:29:03 INFO - Chipset Model: Intel Iris 02:29:03 INFO - Type: GPU 02:29:03 INFO - Bus: Built-In 02:29:03 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:03 INFO - Vendor: Intel (0x8086) 02:29:03 INFO - Device ID: 0x0a2e 02:29:03 INFO - Revision ID: 0x0009 02:29:03 INFO - Displays: 02:29:03 INFO - Display: 02:29:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:03 INFO - Display Serial Number: 1600x1200 60 02:29:03 INFO - Main Display: Yes 02:29:03 INFO - Mirror: Off 02:29:03 INFO - Online: Yes 02:29:03 INFO - Rotation: Supported 02:29:03 INFO - Return code: 0 02:29:03 INFO - Running post-action listener: _resource_record_post_action 02:29:03 INFO - [mozharness: 2017-01-29 10:29:03.804534Z] Finished run-tests step (success) 02:29:03 INFO - Running post-run listener: _resource_record_post_run 02:29:03 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 34902016; Write bytes: 513389568; Read time: 322; Write time: 1483 02:29:03 INFO - TinderboxPrint: CPU usage
54.2% 02:29:03 INFO - TinderboxPrint: I/O read bytes / time
34,902,016 / 322 02:29:03 INFO - TinderboxPrint: I/O write bytes / time
513,389,568 / 1,483 02:29:03 INFO - TinderboxPrint: CPU idle
2,195.9 (45.8%) 02:29:03 INFO - TinderboxPrint: CPU system
963.7 (20.1%) 02:29:03 INFO - TinderboxPrint: CPU user
1,630.0 (34.0%) 02:29:03 INFO - TinderboxPrint: Swap in / out
238,825,472 / 0 02:29:03 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205269504; Write bytes: 200669184; Read time: 15629; Write time: 390 02:29:03 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 32804864; Write bytes: 301751296; Read time: 299; Write time: 1058 02:29:04 INFO - Running post-run listener: _upload_blobber_files 02:29:04 INFO - Blob upload gear active. 02:29:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:04 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 02:29:04 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:29:04 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:29:04 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:29:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:29:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:05 INFO - (blobuploader) - INFO - Done attempting. 02:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:06 INFO - (blobuploader) - INFO - Done attempting. 02:29:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:29:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:29:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:07 INFO - (blobuploader) - INFO - Done attempting. 02:29:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:10 INFO - (blobuploader) - INFO - Done attempting. 02:29:10 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:10 INFO - Return code: 0 02:29:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51afb8c6c3b41a97fd10335ce35c164e61a6e5971d82c3341110a7933d9a292201c9fa9937fa26de813a20a415c29fbe1a5768aaf08fa9bcbac65794fe190217", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea19fb10535c5c30d0a2cc0f43da47dd1a6ccf88bbe81477212d87b8239cfd40a29c1ea9739ef7e3794f75ff5e80648a5ae1e9cbad8ac0d2bf6486054f1a772f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d9cff8d9723bb5dc3cb962c56f1e83b2bd4a9efa169f3f1c32ec8dd3311805e059165f7ccb603a347d70305a90684e7fb97e8d2bdd24a1155d2d2220eb69f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a9f74a15b5f5d6a8880aded1a6c609a3f7f4bc55466a65f3c45ee92793699439cf5abff3925c0fbb125835ad96a76b09f1513f270f43e792f609d54d60b67f0"} 02:29:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:10 INFO - Contents: 02:29:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51afb8c6c3b41a97fd10335ce35c164e61a6e5971d82c3341110a7933d9a292201c9fa9937fa26de813a20a415c29fbe1a5768aaf08fa9bcbac65794fe190217", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea19fb10535c5c30d0a2cc0f43da47dd1a6ccf88bbe81477212d87b8239cfd40a29c1ea9739ef7e3794f75ff5e80648a5ae1e9cbad8ac0d2bf6486054f1a772f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d9cff8d9723bb5dc3cb962c56f1e83b2bd4a9efa169f3f1c32ec8dd3311805e059165f7ccb603a347d70305a90684e7fb97e8d2bdd24a1155d2d2220eb69f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a9f74a15b5f5d6a8880aded1a6c609a3f7f4bc55466a65f3c45ee92793699439cf5abff3925c0fbb125835ad96a76b09f1513f270f43e792f609d54d60b67f0"} 02:29:10 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:10 INFO - Copying logs to upload dir... 02:29:10 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.121047 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-01-29 02:29:10.682457) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 02:29:10.691043) ========= 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.rWdEAy49BG/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.koPXsHfqQv/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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/Mozilla-Aurora/sha512/51afb8c6c3b41a97fd10335ce35c164e61a6e5971d82c3341110a7933d9a292201c9fa9937fa26de813a20a415c29fbe1a5768aaf08fa9bcbac65794fe190217", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea19fb10535c5c30d0a2cc0f43da47dd1a6ccf88bbe81477212d87b8239cfd40a29c1ea9739ef7e3794f75ff5e80648a5ae1e9cbad8ac0d2bf6486054f1a772f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d9cff8d9723bb5dc3cb962c56f1e83b2bd4a9efa169f3f1c32ec8dd3311805e059165f7ccb603a347d70305a90684e7fb97e8d2bdd24a1155d2d2220eb69f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a9f74a15b5f5d6a8880aded1a6c609a3f7f4bc55466a65f3c45ee92793699439cf5abff3925c0fbb125835ad96a76b09f1513f270f43e792f609d54d60b67f0"} build_url:https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010975 build_url: 'https://queue.taskcluster.net/v1/task/AWybPNQfTJKaXFjmkacDcg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51afb8c6c3b41a97fd10335ce35c164e61a6e5971d82c3341110a7933d9a292201c9fa9937fa26de813a20a415c29fbe1a5768aaf08fa9bcbac65794fe190217", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea19fb10535c5c30d0a2cc0f43da47dd1a6ccf88bbe81477212d87b8239cfd40a29c1ea9739ef7e3794f75ff5e80648a5ae1e9cbad8ac0d2bf6486054f1a772f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2d9cff8d9723bb5dc3cb962c56f1e83b2bd4a9efa169f3f1c32ec8dd3311805e059165f7ccb603a347d70305a90684e7fb97e8d2bdd24a1155d2d2220eb69f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a9f74a15b5f5d6a8880aded1a6c609a3f7f4bc55466a65f3c45ee92793699439cf5abff3925c0fbb125835ad96a76b09f1513f270f43e792f609d54d60b67f0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-29 02:29:10.727163) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 02:29:10.727468) ========= 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.rWdEAy49BG/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.koPXsHfqQv/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-29 02:29:10.776092) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 02:29:10.776367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-29 02:29:10.776651) ========= ========= Total master_lag: 0.20 =========