builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0072 starttime: 1488905781.93 results: success (0) buildid: 20170307070455 builduid: 80179df5709c442390575d7167d83433 revision: 577083e85267 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:21.932240) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:21.932815) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:21.933100) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:21.960609) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:21.961062) ========= 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.4erMSFfEsF/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.KYiikBRzpy/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-03-07 08:56:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.80M=0.001s 2017-03-07 08:56:22 (9.80 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115178 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:22.101202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:22.101659) ========= 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.4erMSFfEsF/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.KYiikBRzpy/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.024479 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:22.150770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-07 08:56:22.151193) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 577083e85267 --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 577083e85267 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4erMSFfEsF/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.KYiikBRzpy/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-03-07 08:56:22,250 Setting DEBUG logging. 2017-03-07 08:56:22,250 attempt 1/10 2017-03-07 08:56:22,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/577083e85267?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-07 08:56:23,190 unpacking tar archive at: mozilla-aurora-577083e85267/testing/mozharness/ program finished with exit code 0 elapsedTime=1.202209 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-07 08:56:23.374997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:23.375309) ========= script_repo_revision: 577083e85267 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:23.375713) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:23.375984) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 08:56:23.395873) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-07 08:56:23.396278) ========= /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.4erMSFfEsF/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.KYiikBRzpy/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:56:23 INFO - MultiFileLogger online at 20170307 08:56:23 in /builds/slave/test 08:56:23 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 08:56:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:56:23 INFO - {'append_to_log': False, 08:56:23 INFO - 'base_work_dir': '/builds/slave/test', 08:56:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:56:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:56:23 INFO - 'branch': 'Mozilla-Aurora', 08:56:23 INFO - 'buildbot_json_path': 'buildprops.json', 08:56:23 INFO - 'config_files': ('talos/mac_config.py',), 08:56:23 INFO - 'default_actions': ('clobber', 08:56:23 INFO - 'read-buildbot-config', 08:56:23 INFO - 'download-and-extract', 08:56:23 INFO - 'populate-webroot', 08:56:23 INFO - 'create-virtualenv', 08:56:23 INFO - 'install', 08:56:23 INFO - 'run-tests'), 08:56:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:56:23 INFO - 'download_minidump_stackwalk': True, 08:56:23 INFO - 'download_symbols': 'ondemand', 08:56:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:56:23 INFO - 'tooltool.py': '/tools/tooltool.py', 08:56:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:56:23 INFO - '/tools/misc-python/virtualenv.py')}, 08:56:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:56:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:56:23 INFO - 'gecko_profile': False, 08:56:23 INFO - 'gecko_profile_interval': 0, 08:56:23 INFO - 'installer_path': 'installer.exe', 08:56:23 INFO - 'log_level': 'info', 08:56:23 INFO - 'log_name': 'talos', 08:56:23 INFO - 'log_to_console': True, 08:56:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:56:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:56:23 INFO - 'opt_config_files': (), 08:56:23 INFO - 'pip_index': False, 08:56:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:23 INFO - 'cmd': ('bash', 08:56:23 INFO - '-c', 08:56:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:23 INFO - 'enabled': True, 08:56:23 INFO - 'halt_on_failure': False, 08:56:23 INFO - 'name': 'check_screen_resolution'},), 08:56:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:56:23 INFO - 'cmd': ('bash', 08:56:23 INFO - '-c', 08:56:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:56:23 INFO - 'enabled': True, 08:56:23 INFO - 'halt_on_failure': False, 08:56:23 INFO - 'name': 'check_screen_resolution'},), 08:56:23 INFO - 'run_cmd_checks_enabled': True, 08:56:23 INFO - 'suite': 'dromaeojs-e10s', 08:56:23 INFO - 'system_bits': '32', 08:56:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:56:23 INFO - 'title': 't-yosemite-r7-0072', 08:56:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:56:23 INFO - 'use_talos_json': True, 08:56:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:56:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:56:23 INFO - 'work_dir': 'build'} 08:56:23 INFO - [mozharness: 2017-03-07 16:56:23.614066Z] Running clobber step. 08:56:23 INFO - Running pre-action listener: _resource_record_pre_action 08:56:23 INFO - Running main action method: clobber 08:56:23 INFO - rmtree: /builds/slave/test/build 08:56:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:56:26 INFO - Running post-action listener: _resource_record_post_action 08:56:26 INFO - [mozharness: 2017-03-07 16:56:26.088481Z] Finished clobber step (success) 08:56:26 INFO - [mozharness: 2017-03-07 16:56:26.088644Z] Running read-buildbot-config step. 08:56:26 INFO - Running pre-action listener: _resource_record_pre_action 08:56:26 INFO - Running main action method: read_buildbot_config 08:56:26 INFO - Using buildbot properties: 08:56:26 INFO - { 08:56:26 INFO - "project": "", 08:56:26 INFO - "product": "firefox", 08:56:26 INFO - "script_repo_revision": "production", 08:56:26 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:56:26 INFO - "repository": "", 08:56:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:56:26 INFO - "buildid": "20170307070455", 08:56:26 INFO - "stage_platform": "macosx64", 08:56:26 INFO - "basedir": "/builds/slave/test", 08:56:26 INFO - "nightly_build": "True", 08:56:26 INFO - "buildnumber": 4, 08:56:26 INFO - "slavename": "t-yosemite-r7-0072", 08:56:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:56:26 INFO - "platform": "yosemite_r7", 08:56:26 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:56:26 INFO - "branch": "mozilla-aurora", 08:56:26 INFO - "repo_path": "releases/mozilla-aurora", 08:56:26 INFO - "revision": "577083e85267", 08:56:26 INFO - "pgo_build": "False", 08:56:26 INFO - "builduid": "80179df5709c442390575d7167d83433", 08:56:26 INFO - "slavebuilddir": "test" 08:56:26 INFO - } 08:56:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:56:26 INFO - Running post-action listener: _resource_record_post_action 08:56:26 INFO - [mozharness: 2017-03-07 16:56:26.092021Z] Finished read-buildbot-config step (success) 08:56:26 INFO - [mozharness: 2017-03-07 16:56:26.092160Z] Running download-and-extract step. 08:56:26 INFO - Running pre-action listener: _resource_record_pre_action 08:56:26 INFO - Running main action method: download_and_extract 08:56:26 INFO - mkdir: /builds/slave/test/build/tests 08:56: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')]} 08:56:26 INFO - trying https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:56:26 INFO - Downloading https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:56:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 08:56:27 INFO - Downloaded 1199 bytes. 08:56:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:56:27 INFO - Using the following test package requirements: 08:56:27 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:56:27 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:56:27 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:56:27 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'jsshell-mac.zip'], 08:56:27 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:56:27 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:56:27 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:56:27 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:56:27 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:56:27 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:56:27 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:56:27 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:56:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:56:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:56:27 INFO - Fetch https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:56:28 INFO - Expected file size: 51779136 08:56:28 INFO - Obtained file size: 51779136 08:56:44 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:56:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:56:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:56:44 INFO - Fetch https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:56:46 INFO - Expected file size: 51779136 08:56:46 INFO - Obtained file size: 51779136 08:57:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:57:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:57:01 INFO - Fetch https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:57:02 INFO - Expected file size: 14404424 08:57:02 INFO - Obtained file size: 14404424 08:57:02 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')]} 08:57:02 INFO - trying https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:57:02 INFO - Downloading https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 08:57:05 INFO - Downloaded 74641523 bytes. 08:57:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:57:05 INFO - mkdir: /builds/slave/test/properties 08:57:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:57:05 INFO - Writing to file /builds/slave/test/properties/build_url 08:57:05 INFO - Contents: 08:57:05 INFO - build_url:https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:57:05 INFO - Running post-action listener: _resource_record_post_action 08:57:05 INFO - Running post-action listener: set_extra_try_arguments 08:57:05 INFO - [mozharness: 2017-03-07 16:57:05.694984Z] Finished download-and-extract step (success) 08:57:05 INFO - [mozharness: 2017-03-07 16:57:05.695104Z] Running populate-webroot step. 08:57:05 INFO - Running pre-action listener: _resource_record_pre_action 08:57:05 INFO - Running main action method: populate_webroot 08:57:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:57:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:57:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:57:05 INFO - '--tppagecycles', 08:57:05 INFO - '7'], 08:57:05 INFO - 'tests': ['tsvgm']}}, 08:57:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:57:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:57:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:57:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:57:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['damp', 'tps']}, 08:57:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'tests': ['damp', 'tps']}, 08:57:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['dromaeo_dom']}, 08:57:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:57:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['basic_compositor_video']}, 08:57:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:57:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other-e10s': {'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tsvgx', 08:57:05 INFO - 'tsvgr_opacity', 08:57:05 INFO - 'tart', 08:57:05 INFO - 'tscrollx', 08:57:05 INFO - 'cart', 08:57:05 INFO - 'tsvg_static']}, 08:57:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:57:05 INFO - 'tsvgr_opacity', 08:57:05 INFO - 'tart', 08:57:05 INFO - 'tscrollx', 08:57:05 INFO - 'cart', 08:57:05 INFO - 'tsvg_static']}, 08:57:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tp5o']}, 08:57:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:57:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s', 08:57:05 INFO - '--xperf_path', 08:57:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:05 INFO - 'tests': ['tp5n']}, 08:57:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:57:05 INFO - 'talos_options': ['--xperf_path', 08:57:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:05 INFO - 'tests': ['tp5n']}}, 08:57:05 INFO - 'talos.zip': {'path': '', 08:57:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:57:05 INFO - Running post-action listener: _resource_record_post_action 08:57:05 INFO - [mozharness: 2017-03-07 16:57:05.713096Z] Finished populate-webroot step (success) 08:57:05 INFO - [mozharness: 2017-03-07 16:57:05.713206Z] Running create-virtualenv step. 08:57:05 INFO - Running pre-action listener: _resource_record_pre_action 08:57:05 INFO - Running main action method: create_virtualenv 08:57:05 INFO - Creating virtualenv /builds/slave/test/build/venv 08:57:05 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 08:57:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:57:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:57:05 INFO - Using real prefix '/tools/python27' 08:57:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:57:06 INFO - Installing distribute.............................................................................................................................................................................................done. 08:57:09 INFO - Installing pip.................done. 08:57:09 INFO - Return code: 0 08:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:57:09 INFO - Reading from file tmpfile_stdout 08:57:09 INFO - Output received: 08:57:09 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) 08:57:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:57:09 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')]} 08:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:09 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')]} 08:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:09 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:09 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 08:57:09 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 08:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:09 INFO - 'HOME': '/Users/cltbld', 08:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:09 INFO - 'LOGNAME': 'cltbld', 08:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:09 INFO - 'MOZ_NO_REMOTE': '1', 08:57:09 INFO - 'NO_EM_RESTART': '1', 08:57:09 INFO - 'PAGER': '/bin/cat', 08:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:09 INFO - 'PWD': '/builds/slave/test', 08:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:09 INFO - 'SHELL': '/bin/bash', 08:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:09 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:09 INFO - 'USER': 'cltbld', 08:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:09 INFO - 'XPC_FLAGS': '0x0', 08:57:09 INFO - 'XPC_SERVICE_NAME': '0', 08:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:09 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 08:57:09 INFO - Cleaning up... 08:57:09 INFO - Return code: 0 08:57:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:57:09 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')]} 08:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:09 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')]} 08:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:09 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:09 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 08:57:09 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 08:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:09 INFO - 'HOME': '/Users/cltbld', 08:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:09 INFO - 'LOGNAME': 'cltbld', 08:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:09 INFO - 'MOZ_NO_REMOTE': '1', 08:57:09 INFO - 'NO_EM_RESTART': '1', 08:57:09 INFO - 'PAGER': '/bin/cat', 08:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:09 INFO - 'PWD': '/builds/slave/test', 08:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:09 INFO - 'SHELL': '/bin/bash', 08:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:09 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:09 INFO - 'USER': 'cltbld', 08:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:09 INFO - 'XPC_FLAGS': '0x0', 08:57:09 INFO - 'XPC_SERVICE_NAME': '0', 08:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:10 INFO - Downloading/unpacking psutil>=3.1.1 08:57:10 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 08:57:10 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 08:57:10 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 08:57:10 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 08:57:10 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 08:57:10 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 08:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:14 INFO - Installing collected packages: psutil 08:57:14 INFO - Running setup.py install for psutil 08:57:14 INFO - building 'psutil._psutil_osx' extension 08:57:14 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 08:57:14 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 08:57:14 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 08:57:14 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 08:57:14 INFO - building 'psutil._psutil_posix' extension 08:57:14 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 08:57:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:57:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:57:15 INFO - ^ 08:57:15 INFO - 1 warning generated. 08:57:15 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 08:57:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:15 INFO - Successfully installed psutil 08:57:15 INFO - Cleaning up... 08:57:15 INFO - Return code: 0 08:57:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:15 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:15 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 08:57:15 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 08:57:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:15 INFO - 'HOME': '/Users/cltbld', 08:57:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:15 INFO - 'LOGNAME': 'cltbld', 08:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:15 INFO - 'MOZ_NO_REMOTE': '1', 08:57:15 INFO - 'NO_EM_RESTART': '1', 08:57:15 INFO - 'PAGER': '/bin/cat', 08:57:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:15 INFO - 'PWD': '/builds/slave/test', 08:57:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:15 INFO - 'SHELL': '/bin/bash', 08:57:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:15 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:15 INFO - 'USER': 'cltbld', 08:57:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:15 INFO - 'XPC_FLAGS': '0x0', 08:57:15 INFO - 'XPC_SERVICE_NAME': '0', 08:57:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:57:15 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 08:57:15 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 08:57:15 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 08:57:15 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 08:57:15 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 08:57:15 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 08:57:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:57:18 INFO - Installing collected packages: mozsystemmonitor 08:57:18 INFO - Running setup.py install for mozsystemmonitor 08:57:18 INFO - Successfully installed mozsystemmonitor 08:57:18 INFO - Cleaning up... 08:57:18 INFO - Return code: 0 08:57:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:18 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:18 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 08:57:18 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 08:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:18 INFO - 'HOME': '/Users/cltbld', 08:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:18 INFO - 'LOGNAME': 'cltbld', 08:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:18 INFO - 'MOZ_NO_REMOTE': '1', 08:57:18 INFO - 'NO_EM_RESTART': '1', 08:57:18 INFO - 'PAGER': '/bin/cat', 08:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:18 INFO - 'PWD': '/builds/slave/test', 08:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:18 INFO - 'SHELL': '/bin/bash', 08:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:18 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:18 INFO - 'USER': 'cltbld', 08:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:18 INFO - 'XPC_FLAGS': '0x0', 08:57:18 INFO - 'XPC_SERVICE_NAME': '0', 08:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:18 INFO - Downloading/unpacking jsonschema==2.5.1 08:57:18 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 08:57:18 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 08:57:18 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 08:57:18 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 08:57:18 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 08:57:18 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 08:57:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:57:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:57:21 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 08:57:21 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 08:57:21 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 08:57:21 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 08:57:21 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 08:57:21 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 08:57:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:57:21 INFO - warning: no files found matching '*.txt' 08:57:21 INFO - no previously-included directories found matching 'build' 08:57:21 INFO - no previously-included directories found matching 'dist' 08:57:21 INFO - no previously-included directories found matching '.git*' 08:57:21 INFO - Installing collected packages: jsonschema, functools32 08:57:21 INFO - Running setup.py install for functools32 08:57:22 INFO - warning: no files found matching '*.txt' 08:57:22 INFO - no previously-included directories found matching 'build' 08:57:22 INFO - no previously-included directories found matching 'dist' 08:57:22 INFO - no previously-included directories found matching '.git*' 08:57:22 INFO - Successfully installed jsonschema functools32 08:57:22 INFO - Cleaning up... 08:57:22 INFO - Return code: 0 08:57:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:57:22 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')]} 08:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:22 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')]} 08:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:22 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:22 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 08:57:22 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 08:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:22 INFO - 'HOME': '/Users/cltbld', 08:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:22 INFO - 'LOGNAME': 'cltbld', 08:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:22 INFO - 'MOZ_NO_REMOTE': '1', 08:57:22 INFO - 'NO_EM_RESTART': '1', 08:57:22 INFO - 'PAGER': '/bin/cat', 08:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:22 INFO - 'PWD': '/builds/slave/test', 08:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:22 INFO - 'SHELL': '/bin/bash', 08:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:22 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:22 INFO - 'USER': 'cltbld', 08:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:22 INFO - 'XPC_FLAGS': '0x0', 08:57:22 INFO - 'XPC_SERVICE_NAME': '0', 08:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:57:22 INFO - Cleaning up... 08:57:22 INFO - Return code: 0 08:57:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:22 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')]} 08:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:22 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')]} 08:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:22 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:22 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 08:57:22 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 08:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:22 INFO - 'HOME': '/Users/cltbld', 08:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:22 INFO - 'LOGNAME': 'cltbld', 08:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:22 INFO - 'MOZ_NO_REMOTE': '1', 08:57:22 INFO - 'NO_EM_RESTART': '1', 08:57:22 INFO - 'PAGER': '/bin/cat', 08:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:22 INFO - 'PWD': '/builds/slave/test', 08:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:22 INFO - 'SHELL': '/bin/bash', 08:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:22 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:22 INFO - 'USER': 'cltbld', 08:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:22 INFO - 'XPC_FLAGS': '0x0', 08:57:22 INFO - 'XPC_SERVICE_NAME': '0', 08:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:22 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:22 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 08:57:22 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 08:57:22 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 08:57:22 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 08:57:22 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 08:57:22 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 08:57:25 INFO - Downloading blobuploader-1.2.4.tar.gz 08:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:57:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:57:25 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 08:57:25 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 08:57:25 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 08:57:25 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 08:57:25 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 08:57:25 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 08:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:57:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:57:26 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 08:57:26 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 08:57:26 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 08:57:26 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 08:57:26 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 08:57:26 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 08:57:26 INFO - Downloading docopt-0.6.1.tar.gz 08:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:57:26 INFO - Installing collected packages: blobuploader, requests, docopt 08:57:26 INFO - Running setup.py install for blobuploader 08:57:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:57:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:57:26 INFO - Running setup.py install for requests 08:57:27 INFO - Running setup.py install for docopt 08:57:27 INFO - Successfully installed blobuploader requests docopt 08:57:27 INFO - Cleaning up... 08:57:27 INFO - Return code: 0 08:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:27 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')]} 08:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:27 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')]} 08:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:27 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:27 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 08:57:27 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 08:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:27 INFO - 'HOME': '/Users/cltbld', 08:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:27 INFO - 'LOGNAME': 'cltbld', 08:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:27 INFO - 'MOZ_NO_REMOTE': '1', 08:57:27 INFO - 'NO_EM_RESTART': '1', 08:57:27 INFO - 'PAGER': '/bin/cat', 08:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:27 INFO - 'PWD': '/builds/slave/test', 08:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:27 INFO - 'SHELL': '/bin/bash', 08:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:27 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:27 INFO - 'USER': 'cltbld', 08:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:27 INFO - 'XPC_FLAGS': '0x0', 08:57:27 INFO - 'XPC_SERVICE_NAME': '0', 08:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-7WW6UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ZLX8Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-dU515W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-82Emnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vqL9ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-wo8V1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-A5p0gV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Txl3K4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-irSMwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-RsrWsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-wS0cTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-7obHIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-lI5W8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-MENz17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-N057pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-QJe0fR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:29 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-8jfxNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:57:29 INFO - Running setup.py install for manifestparser 08:57:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:57:29 INFO - Running setup.py install for mozcrash 08:57:29 INFO - Running setup.py install for mozdebug 08:57:29 INFO - Running setup.py install for mozdevice 08:57:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Running setup.py install for mozfile 08:57:30 INFO - Running setup.py install for mozhttpd 08:57:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Running setup.py install for mozinfo 08:57:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Running setup.py install for mozInstall 08:57:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Running setup.py install for mozleak 08:57:30 INFO - Running setup.py install for mozlog 08:57:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:57:30 INFO - Running setup.py install for moznetwork 08:57:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:57:31 INFO - Running setup.py install for mozprocess 08:57:31 INFO - Running setup.py install for mozprofile 08:57:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:31 INFO - Running setup.py install for mozrunner 08:57:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:31 INFO - Running setup.py install for mozscreenshot 08:57:31 INFO - Running setup.py install for moztest 08:57:31 INFO - Running setup.py install for mozversion 08:57:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:32 INFO - Cleaning up... 08:57:32 INFO - Return code: 0 08:57:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:32 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:32 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 08:57:32 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 08:57:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:32 INFO - 'HOME': '/Users/cltbld', 08:57:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:32 INFO - 'LOGNAME': 'cltbld', 08:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:32 INFO - 'MOZ_NO_REMOTE': '1', 08:57:32 INFO - 'NO_EM_RESTART': '1', 08:57:32 INFO - 'PAGER': '/bin/cat', 08:57:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:32 INFO - 'PWD': '/builds/slave/test', 08:57:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:32 INFO - 'SHELL': '/bin/bash', 08:57:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:32 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:32 INFO - 'USER': 'cltbld', 08:57:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:32 INFO - 'XPC_FLAGS': '0x0', 08:57:32 INFO - 'XPC_SERVICE_NAME': '0', 08:57:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-XkMXiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:32 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)) 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-jhF_4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:32 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)) 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-khpZVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:32 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)) 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-vsqpbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:32 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)) 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-_kKkCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:32 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)) 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-W37VTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:32 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)) 08:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:32 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-5jDcfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-aL7UAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ODf5Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-C_YBYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-18_sIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-7G14Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-e73YTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-GdesZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-j84jNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:33 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)) 08:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:33 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-gS4crV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:34 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)) 08:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:34 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ymB5u8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:34 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)) 08:57:34 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)) 08:57:34 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)) 08:57:34 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)) 08:57:34 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)) 08:57:34 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)) 08:57:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:34 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 08:57:34 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 08:57:34 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 08:57:34 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 08:57:34 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 08:57:34 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 08:57:36 INFO - Downloading blessings-1.6.tar.gz 08:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:57:36 INFO - Installing collected packages: blessings 08:57:36 INFO - Running setup.py install for blessings 08:57:37 INFO - Successfully installed blessings 08:57:37 INFO - Cleaning up... 08:57:37 INFO - Return code: 0 08:57:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:57:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:37 INFO - Reading from file tmpfile_stdout 08:57:37 INFO - Current package versions: 08:57:37 INFO - blessings == 1.6 08:57:37 INFO - blobuploader == 1.2.4 08:57:37 INFO - docopt == 0.6.1 08:57:37 INFO - functools32 == 3.2.3-2 08:57:37 INFO - jsonschema == 2.5.1 08:57:37 INFO - manifestparser == 1.1 08:57:37 INFO - mozInstall == 1.12 08:57:37 INFO - mozcrash == 1.0 08:57:37 INFO - mozdebug == 0.1 08:57:37 INFO - mozdevice == 0.49 08:57:37 INFO - mozfile == 1.2 08:57:37 INFO - mozhttpd == 0.7 08:57:37 INFO - mozinfo == 0.9 08:57:37 INFO - mozleak == 0.1 08:57:37 INFO - mozlog == 3.4 08:57:37 INFO - moznetwork == 0.27 08:57:37 INFO - mozprocess == 0.25 08:57:37 INFO - mozprofile == 0.28 08:57:37 INFO - mozrunner == 6.13 08:57:37 INFO - mozscreenshot == 0.1 08:57:37 INFO - mozsystemmonitor == 0.3 08:57:37 INFO - moztest == 0.8 08:57:37 INFO - mozversion == 1.4 08:57:37 INFO - psutil == 3.1.1 08:57:37 INFO - requests == 1.2.3 08:57:37 INFO - wsgiref == 0.1.2 08:57:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:37 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')]} 08:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:37 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')]} 08:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:37 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:37 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 08:57:37 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 08:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:37 INFO - 'HOME': '/Users/cltbld', 08:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:37 INFO - 'LOGNAME': 'cltbld', 08:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:37 INFO - 'MOZ_NO_REMOTE': '1', 08:57:37 INFO - 'NO_EM_RESTART': '1', 08:57:37 INFO - 'PAGER': '/bin/cat', 08:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:37 INFO - 'PWD': '/builds/slave/test', 08:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:37 INFO - 'SHELL': '/bin/bash', 08:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:37 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:37 INFO - 'USER': 'cltbld', 08:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:37 INFO - 'XPC_FLAGS': '0x0', 08:57:37 INFO - 'XPC_SERVICE_NAME': '0', 08:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 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)) 08:57:37 INFO - Cleaning up... 08:57:37 INFO - Return code: 0 08:57:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:57:37 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')]} 08:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:37 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')]} 08:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:37 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 0x10e2e3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e2e6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffee95767a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, '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.4erMSFfEsF/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.KYiikBRzpy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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 08:57:37 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 08:57:37 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 08:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:37 INFO - 'HOME': '/Users/cltbld', 08:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:37 INFO - 'LOGNAME': 'cltbld', 08:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:37 INFO - 'MOZ_NO_REMOTE': '1', 08:57:37 INFO - 'NO_EM_RESTART': '1', 08:57:37 INFO - 'PAGER': '/bin/cat', 08:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:37 INFO - 'PWD': '/builds/slave/test', 08:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:37 INFO - 'SHELL': '/bin/bash', 08:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:37 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:37 INFO - 'USER': 'cltbld', 08:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:37 INFO - 'XPC_FLAGS': '0x0', 08:57:37 INFO - 'XPC_SERVICE_NAME': '0', 08:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:57:37 INFO - Cleaning up... 08:57:37 INFO - Return code: 0 08:57:37 INFO - Running post-action listener: _resource_record_post_action 08:57:37 INFO - Running post-action listener: _start_resource_monitoring 08:57:38 INFO - Starting resource monitoring. 08:57:38 INFO - [mozharness: 2017-03-07 16:57:38.005446Z] Finished create-virtualenv step (success) 08:57:38 INFO - [mozharness: 2017-03-07 16:57:38.006390Z] Running install step. 08:57:38 INFO - Running pre-action listener: _resource_record_pre_action 08:57:38 INFO - Running main action method: install 08:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:38 INFO - Reading from file tmpfile_stdout 08:57:38 INFO - Detecting whether we're running mozinstall >=1.0... 08:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:57:38 INFO - Reading from file tmpfile_stdout 08:57:38 INFO - Output received: 08:57:38 INFO - Usage: mozinstall [options] installer 08:57:38 INFO - Options: 08:57:38 INFO - -h, --help show this help message and exit 08:57:38 INFO - -d DEST, --destination=DEST 08:57:38 INFO - Directory to install application into. [default: 08:57:38 INFO - "/builds/slave/test"] 08:57:38 INFO - --app=APP Application being installed. [default: firefox] 08:57:38 INFO - mkdir: /builds/slave/test/build/application 08:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:57:58 INFO - Reading from file tmpfile_stdout 08:57:58 INFO - Output received: 08:57:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:57:58 INFO - Running post-action listener: _resource_record_post_action 08:57:58 INFO - [mozharness: 2017-03-07 16:57:58.848292Z] Finished install step (success) 08:57:58 INFO - [mozharness: 2017-03-07 16:57:58.848430Z] Running run-tests step. 08:57:58 INFO - Running pre-action listener: _resource_record_pre_action 08:57:58 INFO - Running main action method: run_tests 08:57:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:57:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:57:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:57:58 INFO - 2017-03-07 08:57:58.862 screenresolution[1622:7408] starting screenresolution argv=screenresolution get 08:57:58 INFO - 2017-03-07 08:57:58.865 screenresolution[1622:7408] Display 0: 1600x1200x32@60 08:57:58 INFO - 2017-03-07 08:57:58.871 screenresolution[1623:7410] starting screenresolution argv=screenresolution list 08:57:58 INFO - Available Modes on Display 0 08:57:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:57:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:57:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:57:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:57:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:57:59 INFO - Intel Iris: 08:57:59 INFO - Chipset Model: Intel Iris 08:57:59 INFO - Type: GPU 08:57:59 INFO - Bus: Built-In 08:57:59 INFO - VRAM (Dynamic, Max): 1536 MB 08:57:59 INFO - Vendor: Intel (0x8086) 08:57:59 INFO - Device ID: 0x0a2e 08:57:59 INFO - Revision ID: 0x0009 08:57:59 INFO - Displays: 08:57:59 INFO - Display: 08:57:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:57:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:57:59 INFO - Display Serial Number: 1600x1200 60 08:57:59 INFO - Main Display: Yes 08:57:59 INFO - Mirror: Off 08:57:59 INFO - Online: Yes 08:57:59 INFO - Rotation: Supported 08:57:59 INFO - Return code: 0 08:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:57:59 INFO - Python 2.7.3 08:57:59 INFO - Return code: 0 08:57:59 INFO - grabbing minidump binary from tooltool 08:57:59 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')]} 08:57:59 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 0x10e0c1d30>, '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 0x10dfe8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0b6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:57:59 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 08:57:59 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 08:57:59 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 08:57:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:57:59 INFO - Return code: 0 08:57:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:57:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:57:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:57:59 INFO - ENV: RUST_BACKTRACE is now 1 08:57:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:57:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:57:59 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/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 08:57:59 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/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --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 08:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4erMSFfEsF/Render', 08:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:59 INFO - 'HOME': '/Users/cltbld', 08:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:59 INFO - 'LOGNAME': 'cltbld', 08:57:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:57:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:59 INFO - 'MOZ_NO_REMOTE': '1', 08:57:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:57:59 INFO - 'NO_EM_RESTART': '1', 08:57:59 INFO - 'PAGER': '/bin/cat', 08:57:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:59 INFO - 'PWD': '/builds/slave/test', 08:57:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:59 INFO - 'RUST_BACKTRACE': '1', 08:57:59 INFO - 'SHELL': '/bin/bash', 08:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KYiikBRzpy/Listeners', 08:57:59 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 08:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:59 INFO - 'USER': 'cltbld', 08:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:59 INFO - 'XPC_FLAGS': '0x0', 08:57:59 INFO - 'XPC_SERVICE_NAME': '0', 08:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:59 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/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--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 08:57:59 INFO - mozversion application_buildid: 20170307070455 08:57:59 INFO - mozversion application_changeset: 577083e852674484f8064f45a9b99cf13e1f9b6f 08:57:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:57:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:57:59 INFO - mozversion application_name: Firefox 08:57:59 INFO - mozversion application_remotingname: firefox-dev 08:57:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:57:59 INFO - mozversion application_vendor: Mozilla 08:57:59 INFO - mozversion application_version: 54.0a2 08:57:59 INFO - mozversion platform_buildid: 20170307070455 08:57:59 INFO - mozversion platform_changeset: 577083e852674484f8064f45a9b99cf13e1f9b6f 08:57:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:57:59 INFO - mozversion platform_version: 54.0a2 08:57:59 INFO - using testdate: 1488905879 08:57:59 INFO - actual date: 1488905879 08:57:59 INFO - starting webserver on 'localhost:49246' 08:57:59 INFO - SUITE-START | Running 2 tests 08:57:59 INFO - TEST-START | dromaeo_css 08:57:59 INFO - operating with platform_type : mac_ 08:57:59 INFO - Initialising browser for dromaeo_css test... 08:57:59 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp0CgtUo/profile http://localhost:49246/getInfo.html) 08:58:02 INFO - PROCESS | 1633 | 2017-03-07 08:58:02.457 plugin-container[1636:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x934b, name = 'com.apple.tsm.portname' 08:58:02 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:02 INFO - PROCESS | 1633 | 2017-03-07 08:58:02.458 plugin-container[1636:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 08:58:02 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:02 INFO - PROCESS | 1633 | 2017-03-07 08:58:02.458 plugin-container[1636:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:02 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 08:58:02 INFO - PROCESS | 1633 | colorDepth:24 08:58:02 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 08:58:02 INFO - PROCESS | 1633 | __metrics 08:58:02 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 08:58:03 INFO - TEST-INFO | 1633: exit 0 08:58:03 INFO - Browser initialized. 08:58:03 INFO - Running cycle 1/1 for dromaeo_css test... 08:58:03 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp0CgtUo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:05 INFO - PROCESS | 1637 | 2017-03-07 08:58:05.888 plugin-container[1638:7727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 08:58:05 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:05 INFO - PROCESS | 1637 | 2017-03-07 08:58:05.889 plugin-container[1638:7727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 08:58:05 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:05 INFO - PROCESS | 1637 | 2017-03-07 08:58:05.889 plugin-container[1638:7727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:15 INFO - PROCESS | 1637 | RSS: Main: 221368320 08:58:15 INFO - PROCESS | 1637 | 09:01:19 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 09:01:21 INFO - PROCESS | 1637 | RSS: Main: 238620672 09:01:21 INFO - PROCESS | 1637 | 09:03:03 INFO - PROCESS | 1637 | ************************* 09:03:03 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 09:03:03 INFO - PROCESS | 1637 | 09:03:03 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:03:03 INFO - PROCESS | 1637 | Full stack: 09:03:03 INFO - PROCESS | 1637 | ************************* 09:04:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 09:04:24 INFO - PROCESS | 1637 | RSS: Main: 249253888 09:04:24 INFO - PROCESS | 1637 | 09:07:26 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 09:07:27 INFO - PROCESS | 1637 | RSS: Main: 256925696 09:07:27 INFO - PROCESS | 1637 | 09:10:29 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 09:10:29 INFO - PROCESS | 1637 | RSS: Main: 253280256 09:10:29 INFO - PROCESS | 1637 | 09:13:32 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 09:13:33 INFO - PROCESS | 1637 | RSS: Main: 258220032 09:13:33 INFO - PROCESS | 1637 | 09:16:35 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 09:16:36 INFO - PROCESS | 1637 | RSS: Main: 254533632 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | __start_tp_report 09:16:36 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 09:16:36 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 09:16:36 INFO - PROCESS | 1637 | |i|pagename|runs| 09:16:36 INFO - PROCESS | 1637 | |0;dojo.html;3471.7569770116093;3475.8783442579515;3481.4777783332506;3504.7371447141472;3512.8243587820634;5991.281046274442;6002.939970600298;6013.128096426016;6065.969670151654;6090.87818243636;6004.969975150118;6064.878702425949;6095.3599872013465;6099.664518451484;6120.449159575637;5374.677519348852;5381.596380271476;5451.291332126838;5484.122540393518;5484.122540393538;5428.158635411518;5510.9448905511035;5514.944850551503;5524.723763811813;5548.72256387181;5247.685138891678;5282.313299271109;5467.0705979983295;5471.945280547223;5482.013237617227;5333.813316533902;5336.866578335534;5343.3587969443915;5350.973245133789;5406.54044406226;4400.955990440087;5282.286891269668;5288.497592728685;5321.3348331458565;5404.135338345845;4363.978180109111;5905.114232865105;5938.554608404387;6016.398360163992;6026.638401695912;5349.251104845324;5840.47435730782;6115.877682446326;6249.7187626556915;6296.685165741695;6410.839729006765;6431.292557818636;6433.871322573569;6435.967820160916;6448.838779030515;6108.877822443526;6176.042713379478;6176.598521096114;6188.1027251049245;6214.1921550198185;6233.376662333821;6267.091271765568;6398.072279519537;6436.935630643728;6524.086627872101;5255.3168088148295;6219.098230756515;6234.906476402857;6260.655663938436;6281.120643109968;6417.96791016042;6427.775027874002;6434.485241180694;6436.613803171825;6449.580777249557;6228;6248.50011999039;6260.655663938527;6327.240731112204;6345.206849143857;5302.814401495929;6231.968840155861;6280.654563999024;6287.9056814147825;6314.305426403092;5165.225216217597;5174;5175.844724658266;5182.740862956913;5189.610779191576;3804.2581696569455;4547.704399214041;4580.8167673293365;4599.517050709627;4655.4878963314;6115.088229002436;6132.110769107974;6134.19168101403;6149.907751383733;6151.292601350812;6393.904091438632;6434.227892652818;6449.806505804833;6460.515461340418;6485.805425837232;5827.184194212814;5859.882802344015;6047.697615119227;6115.021596544531;6137.355577664371;5509.338879334506;5569.610127291051;5622.943770562242;5655.406182350876;5683.090705487102;6602.3397660233595;6642.601443913284;6646.136002319668;6649.368310010541;6663.966680166568;5513.786966867283;6390.456811170917;6448.1295025171;6457.773977910751;6477.319881412573;6267.310595834546;6307.117003619589;6457.967710161607;6683.799486015428;6710.429613482714;6296.118543404019;6309.148264984352;6310.7791227306825;6327.272363678236;6333.36666333363;6299.590526615755;6341.934648594182;6364.459020983083;6376.713047912948;6399.424051835357;6155.938440615537;6220.377962203744;6262.749490020348;6269.467095296951;6294.1188233648245;5219.556337711337;6214.5960512566535;6255.937440625536;6282.2147231596055;6294.402031806971;5094.439611642791;6100.054491553817;6286.748530058747;6301.558890877778;6319.557630965788;6187.907181392193;6250.062490626442;6268.247810262797;6305.148804911279;6308.810735677937;6110.4980065817745;6155.076738489083;6187.81436556904;6191.9690401547705;6217.316095229611 09:16:36 INFO - PROCESS | 1637 | |1;ext.html;664.4352300544538;711.1501755402293;745.1728581274784;961.2214106573672;965.4255717847878;37476.625233747654;38327.80836095819;39652;39778.20443591126;39926.800365998126;38224.42663360045;38309.42535861964;38338.80830595843;38369.80815095921;38403.423948640724;8802.823943521142;9084;9106.27149828012;9118.726438206832;9153.99306076331;9007.054259302777;9101.817963640724;9107.635694572242;9120.771980700503;9135.908640913605;9077.500737459437;9137.177654011137;9140.908590914072;9145.176934075931;9165.129312715282;9069.455832650028;9120.087991200828;9124.498152601604;9127.634894604242;9175.587098580512;8649;8681.2186903179;8711.60797764102;8726.476411415335;8756.518391488466;33627;33788.98633040988;34003.6599634003;34144.48783268228;34163.829180853936;3193.409219294438;3679.7792132472136;3707.666310032083;3751.0997360633523;3759.661630453272;24531.509369812684;24596;24936.625950610756;25153.874230628913;25876.611850822253;3016.9245768855735;3099.070278916316;3120.953185702216;3171.7462602991704;3272.427325218101;349004;352518;354182.99999999744;385088;392135.0393248044;35031.29937401238;41143.7942810284;41900.37149442761;41947.790261048496;43739.56260437355;38560;43872.56127438749;44820;44857.65427037194;45290.547094528636;13524.797128043087;13527.729445411036;13610.115342502904;13629;13633.522826701017;11668.94165529184;11836.70408239801;11926.880731192578;11942.343171125498;11943.522259109712;11539.653810385702;11651.650450486499;11667.708307292216;11731;11747.765044699228;5495.972520137374;5573.247611572386;5587.804426845041;5587.8882422352135;5600.4959553640365;1112.7218195451137;1131.575659127827;1139.4587570903755;1165.2288521447354;1185.0874826383636;25750.227493175236;26435.47129057408;26465.47069058608;26696.733032669425;26967.86516067407;1230.563150081726;1241.900647948162;1248.0327745996924;1281.532240732126;1311.6261865368324;11166.694043334724;12203;13524.32378381077;13645.863541364657;13749.106308089942;486192;488309;488481;488545.55727221136;488981;54030.729846350514;54035.729821350644;54165;54222.45777542253;54236.45763542393;1435.8492358302437;1466.4720700547794;1470.2501724120561;1471.6615178509003;1473.2044695864113;24780.87609562013;25487.098472880432;25875.611865822404;26256.21231363062;26302.4739505205;44653.77673111744;44882.55117448914;44908.10183796371;44978.77510612426;45055.32417013682;446574;449334;449410;450709;451658;27540.58689119705;28156.71843281541;29337.11988640344;30651.386972259985;30751.846240768653;36818;37468;37517.81241093777;37519.81240093782;37632;28098.578521321786;30555.004758882824;31695.841520792248;32097.55560999714;32289.35421291608;30685;30827.383452330378;30888.845555772077;31532.369352613277;31854.044378668677;31018;31521;31622.05133845988;31655.52516712205;31909;27739.72260277452;28148.296292592848;28213.57679634766;28231.71768282291;28938.27654308659;21694.45763855853;22184.223552175597;22236.77763222347;22315.33054008382;22383.664245036663 09:16:36 INFO - PROCESS | 1637 | |2;jquery.html;393.76570940290526;409.4002286650055;411.50208248019896;423.57219208599315;448.79804088160336;19658.508537286558;20354.778713277214;21556.676649850266;21656;21919.342419727393;21453.785462145373;21672.674909876325;21949.890250548724;22039.559208815816;22083.889580552073;6406.903896441546;6762.729490820361;6774.9661251693915;6776.932230677679;6779.49153813466;5767.682777447239;7033.788986330418;7345.485815992883;7514.211007844179;7516.473846830724;6242.625442473443;7159.284071592851;7474.103107627092;7498.887516687254;7504.474686771928;7192.784216473514;7359.521631093989;7446.2553744625375;7480.625968701544;7488.663010164556;7187.101612298463;7258.963705181493;7270.054892863894;7282.12614486265;7283.126024876996;20677;25857.353566160808;26091.347716307053;26289.474210515684;26645.200643980712;2635.9604605930926;2856.600075989363;2883.3224192314788;2886.3938572899715;2957.6746557878614;13381;15010;15450.5364839055;15762.211889405484;16631.417900373428;1775.6182420779464;2022.1911235505768;2058.742657167854;2085.708000879878;2089.7492300923986;124098;162799;163012;164503;165639;16822.747658785127;19585.706214406797;20611.58776824455;20767.8961605191;20896.89551552263;31384.37231255408;31795;32147.839260804016;32301.838490807866;32307;8710.477371357612;10122.291439599305;10217.284790064772;10420.06219440254;10434.843477347846;8655.523946182895;8678.78303042429;8680.131986801396;8726.69456569017;8828.779280518038;9538.570764315622;9593.328467007243;9664;9721.9027809721;9738.513074346254;3395.7283417326553;3677.3013127505687;3984.9800750996637;4021.289768528696;4088.7342322748927;1033.5555711044324;1040.3653771199563;1056.75166335911;1059.516676652677;1069.7486090768664;16676.916615416845;20983.475413114793;21051.78948210498;21105.472363190736;21161.894190529256;1061.6443491430302;1063.8297872340388;1091.4433638844246;1095.5836782022939;1096.7970925378831;10284.074433301039;11003.559857605766;11039.889601104047;11052.723681907855;11057.944710276559;742300;755490;759983;769537;775765;134356;175385.71798291718;176931.11534442502;178285;179841.1007944952;1298.577962162301;1322.530501671912;1349.561392547426;1356.2811709794023;1359.666881614011;19241.51896202606;19461.318853840614;19776.90111549433;19809.504762381057;19809.60380792347;64790;65034.67482662557;65448.67275663782;65476.67261663852;65535;73641;83985.58007209926;84493.5775321144;94145.5292723532;95642.0435795661;11161.330320180816;11598.652040438801;11712.180147389601;11812.409379530816;11953.22304050234;10999.34003959765;11606.535738570701;13588.388522516709;13855.861441385458;13869.722605547631;10525.315854469434;11222.719432014264;11397.943010284895;11436.771264574829;11577.015953644035;8963.596638151168;11533.884661153617;11581.710457238636;11707.121965852766;11931.46308416106;10780.598522192066;11338.773224535298;11658.825117623412;11946.581869634521;11970.640880773242;9785.657501987396;11487.942560287145;11711.472983715583;11981.76036479283;12002.75994480123;9536.761580960532;10946.452677366258;11588.94205528967;11649.389253865764;12079.51681932713 09:16:36 INFO - PROCESS | 1637 | |3;mootools.html;1554.5542645339829;1567.0910871694418;1589.5390336802327;1601.2074023358437;1606.734888743357;8939;9568.138867501924;9763.609455621785;11547.769044619114;11564.785697501768;8641.395102342824;10980.780384392327;10989.120870330375;11017.559297628106;11033.117350611952;2398.880055997202;2458.483718419134;2464.039024780328;2465.5192237513693;2473.492933948545;2421.3341331133993;2432.963505547418;2443.364725171459;2459.483508463216;2470.0984140788573;2398.2205783120557;2435.5007223519224;2438.061346381647;2441.7436169202247;2449.8407603505802;2127.489402543378;2376.8828650534306;2411.0717373811035;2422.685050943375;2427.5509030829176;2326.232343326698;2398.3524448398834;2398.844075135128;2401.1355911871888;2407.711074671033;11318.151138664633;11352.602658906899;11387.544498220144;11403.144764142722;11421.771564568662;5571.275734154534;5993.0710739835395;6070.423309785563;6087.269527656666;6087.817365479043;3552.4493703476;3725.2921944830396;3751.4935483709623;3758.868439604628;3768.8680896168503;1187.3113594115393;1198.742270411857;1226.8711785262508;1227.361771878627;1248.6254123762926;15904.602384940468;15938.521844344688;16083.195840207825;16112.677746445006;16128.59678508035;4946.950530494721;5075.3402057732255;5139.383274007068;5150.510701483353;5163.328767260232;12011.459484323052;12018.978386837216;12054.939725301492;12079.396030198455;12083;4893.535114164149;5839.678817664984;5848.590598658053;5850.795222167204;5855.765769369263;4514.503404625489;5235.952809438128;5367.1680889462195;5378.435264297271;5416.864578385493;3498.317828023562;4086.4483294755423;4092.6112019358116;4190.224808410454;4204.201201771653;1076.2681376663786;1089.8583184186004;1095.5836782022939;1103.2222283290262;1104.8784633690289;830.7756905635445;928.9163975242261;934.2992755433424;934.5047923322766;948.7059011706393;6425.678716064178;6485.870282594322;6668.633225172565;6708.942927048445;6761.121054262914;957.5786653872414;984.5667906121283;987.1855719031828;1004.829179039565;1007.8740157480315;3440.466727657167;3612.7109831213966;3754.5682246541996;4072.6741860651077;4138.0689344897155;13698;13883.514077007458;14129.505467308594;14148.575542733735;14226.288685565887;14057.85942140586;14067.929660351632;14117.858821411655;14123.505677301246;14135.434582616786;9766.75583110428;9901.851472227921;10326.690199294033;10508.737281568023;10537.473126343652;6608.669566521751;6636.369544893228;6761.864762704817;6791.286914873867;6942.444604431735;12510.87489125097;12650.430730617329;12815;12870.613881583567;12924.966002719948;12271.938640307098;12976.351182441029;13079.542216022774;13191.67020824487;13272.933635332149;8142.552159631277;8335.041470231;9112.908870911471;9205.677801276923;9392.530373481162;9999.500024998866;10556.419396933243;10687.30532515384;10709.357438553712;10712.250142489951;9104;9124.908750912671;9190.356675032683;9243.76890577714;9312;8786.033536310873;8864.689735859214;8940.105989401269;9111.271098311987;9120.133587309196;7004.929950700428;7530.924690753022;7830.138684744559;7836.255555722425;7933.484323518953;5807.82576522705;5914.970425147847;6039.365866584121;6063.181470501426;6104.8779024420155;5875.970620146872;6002.33974262822;6086.300075491198;6095.573309868267;6124.5712800103565 09:16:36 INFO - PROCESS | 1637 | |4;prototype.html;350.3833253473885;351.50262378734095;358.7363287983332;384.07054927076473;394.87364043506074;4769.546893045163;4770.403699537558;4984.878402359467;5177.326947496825;5342.278792363029;4837.250226214942;5083.66956147851;5169.844904652857;5177.378714554249;5256.211568264762;3521.982390088046;4215.220184265913;4575.38232338635;4586.472555656109;4623.86128416148;4059.7564146151176;4263.211305908417;4541.364209010741;4555.362249285103;4558.72647641141;4462.553744625544;4471.351654010183;4499.842505512308;4505.346724724913;4517.367568540407;3704.8888533344043;4010.3984402339593;4348.912521989431;4582.977085114587;4613.76931153441;3845.192509572993;3863.9613603864163;4070.4504891839815;4332.91334173318;4368.934465983013;4297.39836422901;4799.568038876483;4872.293517439952;5111.591072714174;5158.329417175743;4143.244742246403;4333.783310834446;4841.668541151212;5049.394072711299;5061.797528098871;5119.721723832896;5182.611304152204;5228.764705588258;5642.6864985355805;5700.776697088139;3777.376732839106;3891.416287556861;4189.224993376235;4210.652591170827;4460.925669563669;4785.353977213066;4848.927266091011;5219.451957544479;5728.25532680753;5741.942580574183;4439.62263207631;4524.253498172765;4617.976910115495;4642.53574642533;4645.837395691135;4307.566225082818;4700.929486057712;4720.976395118071;4756.738379389098;4769.211545670373;4114.292568584004;4353.73877567347;5025.572826309804;5055.823046193365;5060.139776238048;4550.840720574764;4923.261510773341;5530.262520052519;5588.497035266764;5670.489655930986;1410.6684929041667;1474.8082749242744;1530.540837748671;1542.5989242796954;1588.4440445843861;1106.8284415915546;1205.5479195301762;1212.3332167307944;1212.781699294118;1220.1032241302812;5234.874501982092;5332.693729887417;5339.038972984822;5348.91976620351;5354.866128346823;5807.867465844204;5829.213056237439;5943.31651860033;5967.37342579023;5972;4556.7721613919175;4814.831480898151;5032.7735251913755;5228.196272286055;5256.553192978639;2758.0622588320066;3029.33354661974;3032.7118923702656;3051.7711171662213;3072.7541796656215;5473.343198816167;5720.570957178228;6126.571140020245;6149.538784591084;6196.721147548371;5205.167173252262;5309.814156504581;5593.664380137205;5665.291838520185;6192.287886892975;6054.788082417094;6145.508359331243;6163.260408750889;6172.8765424691255;6185.690715464209;4756.7145971241835;5407.945450637088;5769.384572319757;5798.594098413239;5841.620294680833;5236.455245702451;5382.03123437785;5517.69652669091;5607.80372686954;5772.643428794146;5264.420913699413;6012.1582978382185;6026.403003204242;6047.155617536693;6063.302720187058;3616.6202548732003;3974.7813870237414;4066.2070896176147;4563.703359281769;4619.699719518355;5382.757775900172;5561.675913072203;5717.285339332583;5750.367459579359;5825.41745825431;4807.519248075165;5588.6646801192055;5606.430199544134;5613.859653508695;5642.548596112383;4673.906521869611;5642.689652069175;5724.599278050497;5731.627444216113;5733.3406658234835;4899.069176856528;5062.696238225718;5298.394215605664;5333.7333133343955;5468.496163554895;4697.953020469751;4928.457869634266;5371.650842695212;5500.834974950758;5760.423957604206;5016.272640467185;5081.644284900021;5171.3018742469285;5390.434004429635;5442.727863606883 09:16:36 INFO - PROCESS | 1637 | |5;yui.html;635.9554831161821;706.9081019467466;759.7796638974697;822.1613953767157;823.5758584329071;15074.24321828753;16059.437919672813;16343.673126537491;16403.83596164038;16404.425845095426;14535;15233.162176080312;15318.923405382957;15400.30698618562;15434.536963891072;923.796764711764;938.6198589571235;939.1359948847042;944.9858252126223;945.9952700236489;773.1958762886591;832.5379414424991;940.6002448959199;942.1379437814392;966.1401352796017;1002.3484734922285;1003.9849402258972;1004.3521928356206;1005.2661557063311;1007.1036777268237;1000.0799264676515;1000.8298589239847;1001.1240164855751;1001.4592120255018;1002.3084071990303;737.1743647115212;737.3584981066507;738.0810890441435;739.3419856327872;739.7263012685341;15756.76364854528;15952.361905523765;16306.592335191597;16652.16739163037;16939.40712075084;13872.861271387359;13988.72022559543;14051.367688454044;14110.506132285424;14149.434022639081;11702.122340824388;11782.823257651142;11806.40967951599;11829.231099978473;12277.815832762515;3265.5591495148087;3574.4995700601676;3575.6603122703564;3605.242898991215;3660.1764602964386;112938;113765;117208;118005.04987375299;118464.40767796192;9886.95056524727;9960;10163.7459063524;10193.592256309812;10436;19528.31650892212;19667.409977700692;19960.20159193616;20086.09612567438;20098.39704808858;7648.80877978055;7653.11989121247;7657.885131723028;7733;7808.726694565663;4231.809568569422;4275.978620106879;4337.868920053451;4351;4534.682572219913;3399.5240666306736;3439.105832483572;3455.170759017868;3475.1659601695915;3495.4232551629207;493.51881915133174;628.2398298059308;643.1510406263687;643.810076027573;649.8602800397842;683.8461346197066;689.1420181873599;702.217028013761;716.8458781362032;738.117949050879;8603.300506513366;9774.657886973922;10129.848052279222;10237.641682541076;10367.222458315657;573.8938296415176;642.3287664390723;687.700850130195;711.1714852197132;719.7732714195116;4779.737114458669;5001;5013.874653133701;5025.949740502549;5030.446650868402;88397.55801220953;96531.51734241284;101055.49472252592;104248.47875760573;106288.46855765671;52022;61889.69055154786;65383;78553.21446785459;88644.55677721699;66652;66749;71568.64215678888;72336;78838;9809.705708828462;10836.458177091272;10934.627986893542;11249.943750281524;11262.324260544394;47685.76157119192;48113;48826;50198.74900625474;50591.73520662013;44806;50306.245406319664;62822.685886570274;64890;64965;12566.685832854251;12586.307753073668;12773.361331933553;12878.806817897552;12892.355382231039;12075.396230188631;14274.8966273104;14449.49426770058;14504.202268875313;14572.489962851248;13486.86513134856;13595.388207530486;13620.31898405056;13754.31228438594;14025.018248722492;13290.20258784476;13708;13779.517716879862;14042.859571404564;14325.85674143287;10652;12064.698382540506;12552.246865188117;12614.684632883884;13167.078304518975;10668.359898406121;10710.57157713714;11056.778864422828;11070.096074283014;11201.495932682887;9818.75453113678;10967.341959482457;10985.137813456306;11153.72115697114;11201.047910927662 09:16:36 INFO - PROCESS | 1637 | __end_tp_report 09:16:36 INFO - PROCESS | 1637 | __start_cc_report 09:16:36 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,411 09:16:36 INFO - PROCESS | 1637 | __end_cc_report 09:16:36 INFO - PROCESS | 1637 | __startTimestamp1488906996197__endTimestamp 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | ------- Summary: start ------- 09:16:36 INFO - PROCESS | 1637 | Number of tests: 6 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5893.29 Median:6155.94 stddev:678.28 (11.0%) stddev-sans-first:653.22 09:16:36 INFO - PROCESS | 1637 | Values: 3471.8 3475.9 3481.5 3504.7 3512.8 5991.3 6002.9 6013.1 6066.0 6090.9 6005.0 6064.9 6095.4 6099.7 6120.4 5374.7 5381.6 5451.3 5484.1 5484.1 5428.2 5510.9 5514.9 5524.7 5548.7 5247.7 5282.3 5467.1 5471.9 5482.0 5333.8 5336.9 5343.4 5351.0 5406.5 4401.0 5282.3 5288.5 5321.3 5404.1 4364.0 5905.1 5938.6 6016.4 6026.6 5349.3 5840.5 6115.9 6249.7 6296.7 6410.8 6431.3 6433.9 6436.0 6448.8 6108.9 6176.0 6176.6 6188.1 6214.2 6233.4 6267.1 6398.1 6436.9 6524.1 5255.3 6219.1 6234.9 6260.7 6281.1 6418.0 6427.8 6434.5 6436.6 6449.6 6228.0 6248.5 6260.7 6327.2 6345.2 5302.8 6232.0 6280.7 6287.9 6314.3 5165.2 5174.0 5175.8 5182.7 5189.6 3804.3 4547.7 4580.8 4599.5 4655.5 6115.1 6132.1 6134.2 6149.9 6151.3 6393.9 6434.2 6449.8 6460.5 6485.8 5827.2 5859.9 6047.7 6115.0 6137.4 5509.3 5569.6 5622.9 5655.4 5683.1 6602.3 6642.6 6646.1 6649.4 6664.0 5513.8 6390.5 6448.1 6457.8 6477.3 6267.3 6307.1 6458.0 6683.8 6710.4 6296.1 6309.1 6310.8 6327.3 6333.4 6299.6 6341.9 6364.5 6376.7 6399.4 6155.9 6220.4 6262.7 6269.5 6294.1 5219.6 6214.6 6255.9 6282.2 6294.4 5094.4 6100.1 6286.7 6301.6 6319.6 6187.9 6250.1 6268.2 6305.1 6308.8 6110.5 6155.1 6187.8 6192.0 6217.3 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:55523.77 Median:191583.91 stddev:116547.12 (60.8%) stddev-sans-first:116801.28 09:16:36 INFO - PROCESS | 1637 | Values: 664.4 711.2 745.2 961.2 965.4 37476.6 38327.8 39652.0 39778.2 39926.8 38224.4 38309.4 38338.8 38369.8 38403.4 8802.8 9084.0 9106.3 9118.7 9154.0 9007.1 9101.8 9107.6 9120.8 9135.9 9077.5 9137.2 9140.9 9145.2 9165.1 9069.5 9120.1 9124.5 9127.6 9175.6 8649.0 8681.2 8711.6 8726.5 8756.5 33627.0 33789.0 34003.7 34144.5 34163.8 3193.4 3679.8 3707.7 3751.1 3759.7 24531.5 24596.0 24936.6 25153.9 25876.6 3016.9 3099.1 3121.0 3171.7 3272.4 349004.0 352518.0 354183.0 385088.0 392135.0 35031.3 41143.8 41900.4 41947.8 43739.6 38560.0 43872.6 44820.0 44857.7 45290.5 13524.8 13527.7 13610.1 13629.0 13633.5 11668.9 11836.7 11926.9 11942.3 11943.5 11539.7 11651.7 11667.7 11731.0 11747.8 5496.0 5573.2 5587.8 5587.9 5600.5 1112.7 1131.6 1139.5 1165.2 1185.1 25750.2 26435.5 26465.5 26696.7 26967.9 1230.6 1241.9 1248.0 1281.5 1311.6 11166.7 12203.0 13524.3 13645.9 13749.1 486192.0 488309.0 488481.0 488545.6 488981.0 54030.7 54035.7 54165.0 54222.5 54236.5 1435.8 1466.5 1470.3 1471.7 1473.2 24780.9 25487.1 25875.6 26256.2 26302.5 44653.8 44882.6 44908.1 44978.8 45055.3 446574.0 449334.0 449410.0 450709.0 451658.0 27540.6 28156.7 29337.1 30651.4 30751.8 36818.0 37468.0 37517.8 37519.8 37632.0 28098.6 30555.0 31695.8 32097.6 32289.4 30685.0 30827.4 30888.8 31532.4 31854.0 31018.0 31521.0 31622.1 31655.5 31909.0 27739.7 28148.3 28213.6 28231.7 28938.3 21694.5 22184.2 22236.8 22315.3 22383.7 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:43896.10 Median:21017.63 stddev:127299.47 (605.7%) stddev-sans-first:127614.68 09:16:36 INFO - PROCESS | 1637 | Values: 393.8 409.4 411.5 423.6 448.8 19658.5 20354.8 21556.7 21656.0 21919.3 21453.8 21672.7 21949.9 22039.6 22083.9 6406.9 6762.7 6775.0 6776.9 6779.5 5767.7 7033.8 7345.5 7514.2 7516.5 6242.6 7159.3 7474.1 7498.9 7504.5 7192.8 7359.5 7446.3 7480.6 7488.7 7187.1 7259.0 7270.1 7282.1 7283.1 20677.0 25857.4 26091.3 26289.5 26645.2 2636.0 2856.6 2883.3 2886.4 2957.7 13381.0 15010.0 15450.5 15762.2 16631.4 1775.6 2022.2 2058.7 2085.7 2089.7 124098.0 162799.0 163012.0 164503.0 165639.0 16822.7 19585.7 20611.6 20767.9 20896.9 31384.4 31795.0 32147.8 32301.8 32307.0 8710.5 10122.3 10217.3 10420.1 10434.8 8655.5 8678.8 8680.1 8726.7 8828.8 9538.6 9593.3 9664.0 9721.9 9738.5 3395.7 3677.3 3985.0 4021.3 4088.7 1033.6 1040.4 1056.8 1059.5 1069.7 16676.9 20983.5 21051.8 21105.5 21161.9 1061.6 1063.8 1091.4 1095.6 1096.8 10284.1 11003.6 11039.9 11052.7 11057.9 742300.0 755490.0 759983.0 769537.0 775765.0 134356.0 175385.7 176931.1 178285.0 179841.1 1298.6 1322.5 1349.6 1356.3 1359.7 19241.5 19461.3 19776.9 19809.5 19809.6 64790.0 65034.7 65448.7 65476.7 65535.0 73641.0 83985.6 84493.6 94145.5 95642.0 11161.3 11598.7 11712.2 11812.4 11953.2 10999.3 11606.5 13588.4 13855.9 13869.7 10525.3 11222.7 11397.9 11436.8 11577.0 8963.6 11533.9 11581.7 11707.1 11931.5 10780.6 11338.8 11658.8 11946.6 11970.6 9785.7 11487.9 11711.5 11981.8 12002.8 9536.8 10946.5 11588.9 11649.4 12079.5 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6837.28 Median:3469.39 stddev:4336.64 (125.0%) stddev-sans-first:4330.64 09:16:36 INFO - PROCESS | 1637 | Values: 1554.6 1567.1 1589.5 1601.2 1606.7 8939.0 9568.1 9763.6 11547.8 11564.8 8641.4 10980.8 10989.1 11017.6 11033.1 2398.9 2458.5 2464.0 2465.5 2473.5 2421.3 2433.0 2443.4 2459.5 2470.1 2398.2 2435.5 2438.1 2441.7 2449.8 2127.5 2376.9 2411.1 2422.7 2427.6 2326.2 2398.4 2398.8 2401.1 2407.7 11318.2 11352.6 11387.5 11403.1 11421.8 5571.3 5993.1 6070.4 6087.3 6087.8 3552.4 3725.3 3751.5 3758.9 3768.9 1187.3 1198.7 1226.9 1227.4 1248.6 15904.6 15938.5 16083.2 16112.7 16128.6 4947.0 5075.3 5139.4 5150.5 5163.3 12011.5 12019.0 12054.9 12079.4 12083.0 4893.5 5839.7 5848.6 5850.8 5855.8 4514.5 5236.0 5367.2 5378.4 5416.9 3498.3 4086.4 4092.6 4190.2 4204.2 1076.3 1089.9 1095.6 1103.2 1104.9 830.8 928.9 934.3 934.5 948.7 6425.7 6485.9 6668.6 6708.9 6761.1 957.6 984.6 987.2 1004.8 1007.9 3440.5 3612.7 3754.6 4072.7 4138.1 13698.0 13883.5 14129.5 14148.6 14226.3 14057.9 14067.9 14117.9 14123.5 14135.4 9766.8 9901.9 10326.7 10508.7 10537.5 6608.7 6636.4 6761.9 6791.3 6942.4 12510.9 12650.4 12815.0 12870.6 12925.0 12271.9 12976.4 13079.5 13191.7 13272.9 8142.6 8335.0 9112.9 9205.7 9392.5 9999.5 10556.4 10687.3 10709.4 10712.3 9104.0 9124.9 9190.4 9243.8 9312.0 8786.0 8864.7 8940.1 9111.3 9120.1 7004.9 7530.9 7830.1 7836.3 7933.5 5807.8 5915.0 6039.4 6063.2 6104.9 5876.0 6002.3 6086.3 6095.6 6124.6 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4678.33 Median:5029.17 stddev:1312.28 (26.1%) stddev-sans-first:1275.12 09:16:36 INFO - PROCESS | 1637 | Values: 350.4 351.5 358.7 384.1 394.9 4769.5 4770.4 4984.9 5177.3 5342.3 4837.3 5083.7 5169.8 5177.4 5256.2 3522.0 4215.2 4575.4 4586.5 4623.9 4059.8 4263.2 4541.4 4555.4 4558.7 4462.6 4471.4 4499.8 4505.3 4517.4 3704.9 4010.4 4348.9 4583.0 4613.8 3845.2 3864.0 4070.5 4332.9 4368.9 4297.4 4799.6 4872.3 5111.6 5158.3 4143.2 4333.8 4841.7 5049.4 5061.8 5119.7 5182.6 5228.8 5642.7 5700.8 3777.4 3891.4 4189.2 4210.7 4460.9 4785.4 4848.9 5219.5 5728.3 5741.9 4439.6 4524.3 4618.0 4642.5 4645.8 4307.6 4700.9 4721.0 4756.7 4769.2 4114.3 4353.7 5025.6 5055.8 5060.1 4550.8 4923.3 5530.3 5588.5 5670.5 1410.7 1474.8 1530.5 1542.6 1588.4 1106.8 1205.5 1212.3 1212.8 1220.1 5234.9 5332.7 5339.0 5348.9 5354.9 5807.9 5829.2 5943.3 5967.4 5972.0 4556.8 4814.8 5032.8 5228.2 5256.6 2758.1 3029.3 3032.7 3051.8 3072.8 5473.3 5720.6 6126.6 6149.5 6196.7 5205.2 5309.8 5593.7 5665.3 6192.3 6054.8 6145.5 6163.3 6172.9 6185.7 4756.7 5407.9 5769.4 5798.6 5841.6 5236.5 5382.0 5517.7 5607.8 5772.6 5264.4 6012.2 6026.4 6047.2 6063.3 3616.6 3974.8 4066.2 4563.7 4619.7 5382.8 5561.7 5717.3 5750.4 5825.4 4807.5 5588.7 5606.4 5613.9 5642.5 4673.9 5642.7 5724.6 5731.6 5733.3 4899.1 5062.7 5298.4 5333.7 5468.5 4698.0 4928.5 5371.7 5500.8 5760.4 5016.3 5081.6 5171.3 5390.4 5442.7 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19616.51 Median:20092.25 stddev:28188.37 (140.3%) stddev-sans-first:28231.42 09:16:36 INFO - PROCESS | 1637 | Values: 636.0 706.9 759.8 822.2 823.6 15074.2 16059.4 16343.7 16403.8 16404.4 14535.0 15233.2 15318.9 15400.3 15434.5 923.8 938.6 939.1 945.0 946.0 773.2 832.5 940.6 942.1 966.1 1002.3 1004.0 1004.4 1005.3 1007.1 1000.1 1000.8 1001.1 1001.5 1002.3 737.2 737.4 738.1 739.3 739.7 15756.8 15952.4 16306.6 16652.2 16939.4 13872.9 13988.7 14051.4 14110.5 14149.4 11702.1 11782.8 11806.4 11829.2 12277.8 3265.6 3574.5 3575.7 3605.2 3660.2 112938.0 113765.0 117208.0 118005.0 118464.4 9887.0 9960.0 10163.7 10193.6 10436.0 19528.3 19667.4 19960.2 20086.1 20098.4 7648.8 7653.1 7657.9 7733.0 7808.7 4231.8 4276.0 4337.9 4351.0 4534.7 3399.5 3439.1 3455.2 3475.2 3495.4 493.5 628.2 643.2 643.8 649.9 683.8 689.1 702.2 716.8 738.1 8603.3 9774.7 10129.8 10237.6 10367.2 573.9 642.3 687.7 711.2 719.8 4779.7 5001.0 5013.9 5025.9 5030.4 88397.6 96531.5 101055.5 104248.5 106288.5 52022.0 61889.7 65383.0 78553.2 88644.6 66652.0 66749.0 71568.6 72336.0 78838.0 9809.7 10836.5 10934.6 11249.9 11262.3 47685.8 48113.0 48826.0 50198.7 50591.7 44806.0 50306.2 62822.7 64890.0 64965.0 12566.7 12586.3 12773.4 12878.8 12892.4 12075.4 14274.9 14449.5 14504.2 14572.5 13486.9 13595.4 13620.3 13754.3 14025.0 13290.2 13708.0 13779.5 14042.9 14325.9 10652.0 12064.7 12552.2 12614.7 13167.1 10668.4 10710.6 11056.8 11070.1 11201.5 9818.8 10967.3 10985.1 11153.7 11201.0 09:16:36 INFO - PROCESS | 1637 | -------- Summary: end -------- 09:16:36 INFO - PROCESS | 1637 | 09:16:36 INFO - TEST-INFO | 1637: exit 0 09:16:36 INFO - TEST-OK | dromaeo_css | took 1117420ms 09:16:36 INFO - TEST-START | kraken 09:16:36 INFO - operating with platform_type : mac_ 09:16:36 INFO - Initialising browser for kraken test... 09:16:36 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmphDRYY5/profile http://localhost:49246/getInfo.html) 09:16:39 INFO - PROCESS | 1647 | 2017-03-07 09:16:39.530 plugin-container[1649:10171] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 09:16:39 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:39 INFO - PROCESS | 1647 | 2017-03-07 09:16:39.531 plugin-container[1649:10171] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:16:39 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:39 INFO - PROCESS | 1647 | 2017-03-07 09:16:39.531 plugin-container[1649:10171] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:39 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:16:39 INFO - PROCESS | 1647 | colorDepth:24 09:16:39 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:16:39 INFO - PROCESS | 1647 | __metrics 09:16:39 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:16:40 INFO - TEST-INFO | 1647: exit 0 09:16:40 INFO - Browser initialized. 09:16:40 INFO - Running cycle 1/1 for kraken test... 09:16:40 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmphDRYY5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:42 INFO - PROCESS | 1650 | 2017-03-07 09:16:42.930 plugin-container[1651:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:16:42 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:42 INFO - PROCESS | 1650 | 2017-03-07 09:16:42.930 plugin-container[1651:10293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1b, name = 'com.apple.CFPasteboardClient' 09:16:42 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:16:42 INFO - PROCESS | 1650 | 2017-03-07 09:16:42.930 plugin-container[1651:10293] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:16:52 INFO - PROCESS | 1650 | RSS: Main: 220356608 09:16:52 INFO - PROCESS | 1650 | 09:16:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 09:16:56 INFO - PROCESS | 1650 | RSS: Main: 234844160 09:16:56 INFO - PROCESS | 1650 | 09:16:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 09:16:59 INFO - PROCESS | 1650 | RSS: Main: 243023872 09:16:59 INFO - PROCESS | 1650 | 09:17:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 09:17:03 INFO - PROCESS | 1650 | RSS: Main: 246841344 09:17:03 INFO - PROCESS | 1650 | 09:17:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 09:17:06 INFO - PROCESS | 1650 | RSS: Main: 247316480 09:17:06 INFO - PROCESS | 1650 | 09:17:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:17:08 INFO - PROCESS | 1650 | RSS: Main: 248508416 09:17:08 INFO - PROCESS | 1650 | 09:17:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 09:17:13 INFO - PROCESS | 1650 | RSS: Main: 250044416 09:17:13 INFO - PROCESS | 1650 | 09:17:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 09:17:17 INFO - PROCESS | 1650 | RSS: Main: 251027456 09:17:17 INFO - PROCESS | 1650 | 09:17:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 09:17:21 INFO - PROCESS | 1650 | RSS: Main: 251371520 09:17:21 INFO - PROCESS | 1650 | 09:17:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:17:23 INFO - PROCESS | 1650 | RSS: Main: 250761216 09:17:23 INFO - PROCESS | 1650 | 09:17:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:17:26 INFO - PROCESS | 1650 | RSS: Main: 250777600 09:17:26 INFO - PROCESS | 1650 | 09:17:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:17:28 INFO - PROCESS | 1650 | RSS: Main: 250736640 09:17:28 INFO - PROCESS | 1650 | 09:17:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:17:32 INFO - PROCESS | 1650 | RSS: Main: 251150336 09:17:32 INFO - PROCESS | 1650 | 09:17:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:17:36 INFO - PROCESS | 1650 | RSS: Main: 250916864 09:17:36 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 09:17:38 INFO - PROCESS | 1650 | RSS: Main: 254554112 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | __start_tp_report 09:17:38 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:17:38 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:17:38 INFO - PROCESS | 1650 | |i|pagename|runs| 09:17:38 INFO - PROCESS | 1650 | |0;ai-astar;115;120;120;120;119;120;121;118;119;121 09:17:38 INFO - PROCESS | 1650 | |1;audio-beat-detection;143;151;98;135;136;100;152;141;161;145 09:17:38 INFO - PROCESS | 1650 | |2;audio-dft;200;201;208;199;204;200;201;202;215;283 09:17:38 INFO - PROCESS | 1650 | |3;audio-fft;83;62;59;87;64;60;62;60;96;81 09:17:38 INFO - PROCESS | 1650 | |4;audio-oscillator;90;70;86;74;72;70;78;80;99;102 09:17:38 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;132;105;133;128;118;133;92;78;104;135 09:17:38 INFO - PROCESS | 1650 | |6;imaging-darkroom;98;98;99;97;98;99;125;99;148;117 09:17:38 INFO - PROCESS | 1650 | |7;imaging-desaturate;91;92;93;93;82;141;147;123;141;144 09:17:38 INFO - PROCESS | 1650 | |8;json-parse-financial;64;64;64;65;69;66;67;68;67;67 09:17:38 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;58;61;62;64;63;75;84;81;59;59 09:17:38 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;86;83;87;85;87;87;84;90;85;73 09:17:38 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;139;155;149;148;140;137;143;139;203;179 09:17:38 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;157;227;214;188;168;168;188;209;176;170 09:17:38 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;65;81;85;86;86;81;66;69;93;81 09:17:38 INFO - PROCESS | 1650 | __end_tp_report 09:17:38 INFO - PROCESS | 1650 | __start_cc_report 09:17:38 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1021 09:17:38 INFO - PROCESS | 1650 | __end_cc_report 09:17:38 INFO - PROCESS | 1650 | __startTimestamp1488907058389__endTimestamp 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:17:38 INFO - PROCESS | 1650 | Number of tests: 14 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:119.30 Median:120.00 stddev:1.77 (1.5%) stddev-sans-first:0.97 09:17:38 INFO - PROCESS | 1650 | Values: 115.0 120.0 120.0 120.0 119.0 120.0 121.0 118.0 119.0 121.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:148.00 stddev:21.09 (14.2%) stddev-sans-first:22.22 09:17:38 INFO - PROCESS | 1650 | Values: 143.0 151.0 98.0 135.0 136.0 100.0 152.0 141.0 161.0 145.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:211.30 Median:203.00 stddev:25.66 (12.6%) stddev-sans-first:26.88 09:17:38 INFO - PROCESS | 1650 | Values: 200.0 201.0 208.0 199.0 204.0 200.0 201.0 202.0 215.0 283.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:71.40 Median:72.50 stddev:13.83 (19.1%) stddev-sans-first:14.01 09:17:38 INFO - PROCESS | 1650 | Values: 83.0 62.0 59.0 87.0 64.0 60.0 62.0 60.0 96.0 81.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:79.00 stddev:11.74 (14.9%) stddev-sans-first:12.10 09:17:38 INFO - PROCESS | 1650 | Values: 90.0 70.0 86.0 74.0 72.0 70.0 78.0 80.0 99.0 102.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:133.00 stddev:20.08 (15.1%) stddev-sans-first:20.42 09:17:38 INFO - PROCESS | 1650 | Values: 132.0 105.0 133.0 128.0 118.0 133.0 92.0 78.0 104.0 135.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:107.80 Median:98.00 stddev:17.11 (17.5%) stddev-sans-first:17.77 09:17:38 INFO - PROCESS | 1650 | Values: 98.0 98.0 99.0 97.0 98.0 99.0 125.0 99.0 148.0 117.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:114.70 Median:86.50 stddev:26.75 (30.9%) stddev-sans-first:26.97 09:17:38 INFO - PROCESS | 1650 | Values: 91.0 92.0 93.0 93.0 82.0 141.0 147.0 123.0 141.0 144.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:66.10 Median:67.00 stddev:1.79 (2.7%) stddev-sans-first:1.73 09:17:38 INFO - PROCESS | 1650 | Values: 64.0 64.0 64.0 65.0 69.0 66.0 67.0 68.0 67.0 67.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:63.50 stddev:9.67 (15.2%) stddev-sans-first:9.75 09:17:38 INFO - PROCESS | 1650 | Values: 58.0 61.0 62.0 64.0 63.0 75.0 84.0 81.0 59.0 59.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:86.50 stddev:4.55 (5.3%) stddev-sans-first:4.80 09:17:38 INFO - PROCESS | 1650 | Values: 86.0 83.0 87.0 85.0 87.0 87.0 84.0 90.0 85.0 73.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:153.20 Median:148.50 stddev:21.44 (14.4%) stddev-sans-first:22.12 09:17:38 INFO - PROCESS | 1650 | Values: 139.0 155.0 149.0 148.0 140.0 137.0 143.0 139.0 203.0 179.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:188.00 stddev:23.20 (12.3%) stddev-sans-first:22.02 09:17:38 INFO - PROCESS | 1650 | Values: 157.0 227.0 214.0 188.0 168.0 168.0 188.0 209.0 176.0 170.0 09:17:38 INFO - PROCESS | 1650 | 09:17:38 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.30 Median:83.00 stddev:9.46 (11.4%) stddev-sans-first:8.51 09:17:38 INFO - PROCESS | 1650 | Values: 65.0 81.0 85.0 86.0 86.0 81.0 66.0 69.0 93.0 81.0 09:17:38 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:17:38 INFO - PROCESS | 1650 | 09:17:39 INFO - TEST-INFO | 1650: exit 0 09:17:39 INFO - TEST-OK | kraken | took 62373ms 09:17:39 INFO - SUITE-END | took 1179s 09:17:39 INFO - Completed test suite (00:19:40) 09:17:39 INFO - javascript benchmark 09:17:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7672.959821051105, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3471.7569770116093, 3475.8783442579515, 3481.4777783332506, 3504.7371447141472, 3512.8243587820634, 5991.281046274442, 6002.939970600298, 6013.128096426016, 6065.969670151654, 6090.87818243636, 6004.969975150118, 6064.878702425949, 6095.3599872013465, 6099.664518451484, 6120.449159575637, 5374.677519348852, 5381.596380271476, 5451.291332126838, 5484.122540393518, 5484.122540393538, 5428.158635411518, 5510.9448905511035, 5514.944850551503, 5524.723763811813, 5548.72256387181, 5247.685138891678, 5282.313299271109, 5467.0705979983295, 5471.945280547223, 5482.013237617227, 5333.813316533902, 5336.866578335534, 5343.3587969443915, 5350.973245133789, 5406.54044406226, 4400.955990440087, 5282.286891269668, 5288.497592728685, 5321.3348331458565, 5404.135338345845, 4363.978180109111, 5905.114232865105, 5938.554608404387, 6016.398360163992, 6026.638401695912, 5349.251104845324, 5840.47435730782, 6115.877682446326, 6249.7187626556915, 6296.685165741695, 6410.839729006765, 6431.292557818636, 6433.871322573569, 6435.967820160916, 6448.838779030515, 6108.877822443526, 6176.042713379478, 6176.598521096114, 6188.1027251049245, 6214.1921550198185, 6233.376662333821, 6267.091271765568, 6398.072279519537, 6436.935630643728, 6524.086627872101, 5255.3168088148295, 6219.098230756515, 6234.906476402857, 6260.655663938436, 6281.120643109968, 6417.96791016042, 6427.775027874002, 6434.485241180694, 6436.613803171825, 6449.580777249557, 6228.0, 6248.50011999039, 6260.655663938527, 6327.240731112204, 6345.206849143857, 5302.814401495929, 6231.968840155861, 6280.654563999024, 6287.9056814147825, 6314.305426403092, 5165.225216217597, 5174.0, 5175.844724658266, 5182.740862956913, 5189.610779191576, 3804.2581696569455, 4547.704399214041, 4580.8167673293365, 4599.517050709627, 4655.4878963314, 6115.088229002436, 6132.110769107974, 6134.19168101403, 6149.907751383733, 6151.292601350812, 6393.904091438632, 6434.227892652818, 6449.806505804833, 6460.515461340418, 6485.805425837232, 5827.184194212814, 5859.882802344015, 6047.697615119227, 6115.021596544531, 6137.355577664371, 5509.338879334506, 5569.610127291051, 5622.943770562242, 5655.406182350876, 5683.090705487102, 6602.3397660233595, 6642.601443913284, 6646.136002319668, 6649.368310010541, 6663.966680166568, 5513.786966867283, 6390.456811170917, 6448.1295025171, 6457.773977910751, 6477.319881412573, 6267.310595834546, 6307.117003619589, 6457.967710161607, 6683.799486015428, 6710.429613482714, 6296.118543404019, 6309.148264984352, 6310.7791227306825, 6327.272363678236, 6333.36666333363, 6299.590526615755, 6341.934648594182, 6364.459020983083, 6376.713047912948, 6399.424051835357, 6155.938440615537, 6220.377962203744, 6262.749490020348, 6269.467095296951, 6294.1188233648245, 5219.556337711337, 6214.5960512566535, 6255.937440625536, 6282.2147231596055, 6294.402031806971, 5094.439611642791, 6100.054491553817, 6286.748530058747, 6301.558890877778, 6319.557630965788, 6187.907181392193, 6250.062490626442, 6268.247810262797, 6305.148804911279, 6308.810735677937, 6110.4980065817745, 6155.076738489083, 6187.81436556904, 6191.9690401547705, 6217.316095229611], "value": 5852.122861145625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.4352300544538, 711.1501755402293, 745.1728581274784, 961.2214106573672, 965.4255717847878, 37476.625233747654, 38327.80836095819, 39652.0, 39778.20443591126, 39926.800365998126, 38224.42663360045, 38309.42535861964, 38338.80830595843, 38369.80815095921, 38403.423948640724, 8802.823943521142, 9084.0, 9106.27149828012, 9118.726438206832, 9153.99306076331, 9007.054259302777, 9101.817963640724, 9107.635694572242, 9120.771980700503, 9135.908640913605, 9077.500737459437, 9137.177654011137, 9140.908590914072, 9145.176934075931, 9165.129312715282, 9069.455832650028, 9120.087991200828, 9124.498152601604, 9127.634894604242, 9175.587098580512, 8649.0, 8681.2186903179, 8711.60797764102, 8726.476411415335, 8756.518391488466, 33627.0, 33788.98633040988, 34003.6599634003, 34144.48783268228, 34163.829180853936, 3193.409219294438, 3679.7792132472136, 3707.666310032083, 3751.0997360633523, 3759.661630453272, 24531.509369812684, 24596.0, 24936.625950610756, 25153.874230628913, 25876.611850822253, 3016.9245768855735, 3099.070278916316, 3120.953185702216, 3171.7462602991704, 3272.427325218101, 349004.0, 352518.0, 354182.99999999744, 385088.0, 392135.0393248044, 35031.29937401238, 41143.7942810284, 41900.37149442761, 41947.790261048496, 43739.56260437355, 38560.0, 43872.56127438749, 44820.0, 44857.65427037194, 45290.547094528636, 13524.797128043087, 13527.729445411036, 13610.115342502904, 13629.0, 13633.522826701017, 11668.94165529184, 11836.70408239801, 11926.880731192578, 11942.343171125498, 11943.522259109712, 11539.653810385702, 11651.650450486499, 11667.708307292216, 11731.0, 11747.765044699228, 5495.972520137374, 5573.247611572386, 5587.804426845041, 5587.8882422352135, 5600.4959553640365, 1112.7218195451137, 1131.575659127827, 1139.4587570903755, 1165.2288521447354, 1185.0874826383636, 25750.227493175236, 26435.47129057408, 26465.47069058608, 26696.733032669425, 26967.86516067407, 1230.563150081726, 1241.900647948162, 1248.0327745996924, 1281.532240732126, 1311.6261865368324, 11166.694043334724, 12203.0, 13524.32378381077, 13645.863541364657, 13749.106308089942, 486192.0, 488309.0, 488481.0, 488545.55727221136, 488981.0, 54030.729846350514, 54035.729821350644, 54165.0, 54222.45777542253, 54236.45763542393, 1435.8492358302437, 1466.4720700547794, 1470.2501724120561, 1471.6615178509003, 1473.2044695864113, 24780.87609562013, 25487.098472880432, 25875.611865822404, 26256.21231363062, 26302.4739505205, 44653.77673111744, 44882.55117448914, 44908.10183796371, 44978.77510612426, 45055.32417013682, 446574.0, 449334.0, 449410.0, 450709.0, 451658.0, 27540.58689119705, 28156.71843281541, 29337.11988640344, 30651.386972259985, 30751.846240768653, 36818.0, 37468.0, 37517.81241093777, 37519.81240093782, 37632.0, 28098.578521321786, 30555.004758882824, 31695.841520792248, 32097.55560999714, 32289.35421291608, 30685.0, 30827.383452330378, 30888.845555772077, 31532.369352613277, 31854.044378668677, 31018.0, 31521.0, 31622.05133845988, 31655.52516712205, 31909.0, 27739.72260277452, 28148.296292592848, 28213.57679634766, 28231.71768282291, 28938.27654308659, 21694.45763855853, 22184.223552175597, 22236.77763222347, 22315.33054008382, 22383.664245036663], "value": 17774.185020797995, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.76570940290526, 409.4002286650055, 411.50208248019896, 423.57219208599315, 448.79804088160336, 19658.508537286558, 20354.778713277214, 21556.676649850266, 21656.0, 21919.342419727393, 21453.785462145373, 21672.674909876325, 21949.890250548724, 22039.559208815816, 22083.889580552073, 6406.903896441546, 6762.729490820361, 6774.9661251693915, 6776.932230677679, 6779.49153813466, 5767.682777447239, 7033.788986330418, 7345.485815992883, 7514.211007844179, 7516.473846830724, 6242.625442473443, 7159.284071592851, 7474.103107627092, 7498.887516687254, 7504.474686771928, 7192.784216473514, 7359.521631093989, 7446.2553744625375, 7480.625968701544, 7488.663010164556, 7187.101612298463, 7258.963705181493, 7270.054892863894, 7282.12614486265, 7283.126024876996, 20677.0, 25857.353566160808, 26091.347716307053, 26289.474210515684, 26645.200643980712, 2635.9604605930926, 2856.600075989363, 2883.3224192314788, 2886.3938572899715, 2957.6746557878614, 13381.0, 15010.0, 15450.5364839055, 15762.211889405484, 16631.417900373428, 1775.6182420779464, 2022.1911235505768, 2058.742657167854, 2085.708000879878, 2089.7492300923986, 124098.0, 162799.0, 163012.0, 164503.0, 165639.0, 16822.747658785127, 19585.706214406797, 20611.58776824455, 20767.8961605191, 20896.89551552263, 31384.37231255408, 31795.0, 32147.839260804016, 32301.838490807866, 32307.0, 8710.477371357612, 10122.291439599305, 10217.284790064772, 10420.06219440254, 10434.843477347846, 8655.523946182895, 8678.78303042429, 8680.131986801396, 8726.69456569017, 8828.779280518038, 9538.570764315622, 9593.328467007243, 9664.0, 9721.9027809721, 9738.513074346254, 3395.7283417326553, 3677.3013127505687, 3984.9800750996637, 4021.289768528696, 4088.7342322748927, 1033.5555711044324, 1040.3653771199563, 1056.75166335911, 1059.516676652677, 1069.7486090768664, 16676.916615416845, 20983.475413114793, 21051.78948210498, 21105.472363190736, 21161.894190529256, 1061.6443491430302, 1063.8297872340388, 1091.4433638844246, 1095.5836782022939, 1096.7970925378831, 10284.074433301039, 11003.559857605766, 11039.889601104047, 11052.723681907855, 11057.944710276559, 742300.0, 755490.0, 759983.0, 769537.0, 775765.0, 134356.0, 175385.71798291718, 176931.11534442502, 178285.0, 179841.1007944952, 1298.577962162301, 1322.530501671912, 1349.561392547426, 1356.2811709794023, 1359.666881614011, 19241.51896202606, 19461.318853840614, 19776.90111549433, 19809.504762381057, 19809.60380792347, 64790.0, 65034.67482662557, 65448.67275663782, 65476.67261663852, 65535.0, 73641.0, 83985.58007209926, 84493.5775321144, 94145.5292723532, 95642.0435795661, 11161.330320180816, 11598.652040438801, 11712.180147389601, 11812.409379530816, 11953.22304050234, 10999.34003959765, 11606.535738570701, 13588.388522516709, 13855.861441385458, 13869.722605547631, 10525.315854469434, 11222.719432014264, 11397.943010284895, 11436.771264574829, 11577.015953644035, 8963.596638151168, 11533.884661153617, 11581.710457238636, 11707.121965852766, 11931.46308416106, 10780.598522192066, 11338.773224535298, 11658.825117623412, 11946.581869634521, 11970.640880773242, 9785.657501987396, 11487.942560287145, 11711.472983715583, 11981.76036479283, 12002.75994480123, 9536.761580960532, 10946.452677366258, 11588.94205528967, 11649.389253865764, 12079.51681932713], "value": 11732.699156907524, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.5542645339829, 1567.0910871694418, 1589.5390336802327, 1601.2074023358437, 1606.734888743357, 8939.0, 9568.138867501924, 9763.609455621785, 11547.769044619114, 11564.785697501768, 8641.395102342824, 10980.780384392327, 10989.120870330375, 11017.559297628106, 11033.117350611952, 2398.880055997202, 2458.483718419134, 2464.039024780328, 2465.5192237513693, 2473.492933948545, 2421.3341331133993, 2432.963505547418, 2443.364725171459, 2459.483508463216, 2470.0984140788573, 2398.2205783120557, 2435.5007223519224, 2438.061346381647, 2441.7436169202247, 2449.8407603505802, 2127.489402543378, 2376.8828650534306, 2411.0717373811035, 2422.685050943375, 2427.5509030829176, 2326.232343326698, 2398.3524448398834, 2398.844075135128, 2401.1355911871888, 2407.711074671033, 11318.151138664633, 11352.602658906899, 11387.544498220144, 11403.144764142722, 11421.771564568662, 5571.275734154534, 5993.0710739835395, 6070.423309785563, 6087.269527656666, 6087.817365479043, 3552.4493703476, 3725.2921944830396, 3751.4935483709623, 3758.868439604628, 3768.8680896168503, 1187.3113594115393, 1198.742270411857, 1226.8711785262508, 1227.361771878627, 1248.6254123762926, 15904.602384940468, 15938.521844344688, 16083.195840207825, 16112.677746445006, 16128.59678508035, 4946.950530494721, 5075.3402057732255, 5139.383274007068, 5150.510701483353, 5163.328767260232, 12011.459484323052, 12018.978386837216, 12054.939725301492, 12079.396030198455, 12083.0, 4893.535114164149, 5839.678817664984, 5848.590598658053, 5850.795222167204, 5855.765769369263, 4514.503404625489, 5235.952809438128, 5367.1680889462195, 5378.435264297271, 5416.864578385493, 3498.317828023562, 4086.4483294755423, 4092.6112019358116, 4190.224808410454, 4204.201201771653, 1076.2681376663786, 1089.8583184186004, 1095.5836782022939, 1103.2222283290262, 1104.8784633690289, 830.7756905635445, 928.9163975242261, 934.2992755433424, 934.5047923322766, 948.7059011706393, 6425.678716064178, 6485.870282594322, 6668.633225172565, 6708.942927048445, 6761.121054262914, 957.5786653872414, 984.5667906121283, 987.1855719031828, 1004.829179039565, 1007.8740157480315, 3440.466727657167, 3612.7109831213966, 3754.5682246541996, 4072.6741860651077, 4138.0689344897155, 13698.0, 13883.514077007458, 14129.505467308594, 14148.575542733735, 14226.288685565887, 14057.85942140586, 14067.929660351632, 14117.858821411655, 14123.505677301246, 14135.434582616786, 9766.75583110428, 9901.851472227921, 10326.690199294033, 10508.737281568023, 10537.473126343652, 6608.669566521751, 6636.369544893228, 6761.864762704817, 6791.286914873867, 6942.444604431735, 12510.87489125097, 12650.430730617329, 12815.0, 12870.613881583567, 12924.966002719948, 12271.938640307098, 12976.351182441029, 13079.542216022774, 13191.67020824487, 13272.933635332149, 8142.552159631277, 8335.041470231, 9112.908870911471, 9205.677801276923, 9392.530373481162, 9999.500024998866, 10556.419396933243, 10687.30532515384, 10709.357438553712, 10712.250142489951, 9104.0, 9124.908750912671, 9190.356675032683, 9243.76890577714, 9312.0, 8786.033536310873, 8864.689735859214, 8940.105989401269, 9111.271098311987, 9120.133587309196, 7004.929950700428, 7530.924690753022, 7830.138684744559, 7836.255555722425, 7933.484323518953, 5807.82576522705, 5914.970425147847, 6039.365866584121, 6063.181470501426, 6104.8779024420155, 5875.970620146872, 6002.33974262822, 6086.300075491198, 6095.573309868267, 6124.5712800103565], "value": 5198.8977580181745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.3833253473885, 351.50262378734095, 358.7363287983332, 384.07054927076473, 394.87364043506074, 4769.546893045163, 4770.403699537558, 4984.878402359467, 5177.326947496825, 5342.278792363029, 4837.250226214942, 5083.66956147851, 5169.844904652857, 5177.378714554249, 5256.211568264762, 3521.982390088046, 4215.220184265913, 4575.38232338635, 4586.472555656109, 4623.86128416148, 4059.7564146151176, 4263.211305908417, 4541.364209010741, 4555.362249285103, 4558.72647641141, 4462.553744625544, 4471.351654010183, 4499.842505512308, 4505.346724724913, 4517.367568540407, 3704.8888533344043, 4010.3984402339593, 4348.912521989431, 4582.977085114587, 4613.76931153441, 3845.192509572993, 3863.9613603864163, 4070.4504891839815, 4332.91334173318, 4368.934465983013, 4297.39836422901, 4799.568038876483, 4872.293517439952, 5111.591072714174, 5158.329417175743, 4143.244742246403, 4333.783310834446, 4841.668541151212, 5049.394072711299, 5061.797528098871, 5119.721723832896, 5182.611304152204, 5228.764705588258, 5642.6864985355805, 5700.776697088139, 3777.376732839106, 3891.416287556861, 4189.224993376235, 4210.652591170827, 4460.925669563669, 4785.353977213066, 4848.927266091011, 5219.451957544479, 5728.25532680753, 5741.942580574183, 4439.62263207631, 4524.253498172765, 4617.976910115495, 4642.53574642533, 4645.837395691135, 4307.566225082818, 4700.929486057712, 4720.976395118071, 4756.738379389098, 4769.211545670373, 4114.292568584004, 4353.73877567347, 5025.572826309804, 5055.823046193365, 5060.139776238048, 4550.840720574764, 4923.261510773341, 5530.262520052519, 5588.497035266764, 5670.489655930986, 1410.6684929041667, 1474.8082749242744, 1530.540837748671, 1542.5989242796954, 1588.4440445843861, 1106.8284415915546, 1205.5479195301762, 1212.3332167307944, 1212.781699294118, 1220.1032241302812, 5234.874501982092, 5332.693729887417, 5339.038972984822, 5348.91976620351, 5354.866128346823, 5807.867465844204, 5829.213056237439, 5943.31651860033, 5967.37342579023, 5972.0, 4556.7721613919175, 4814.831480898151, 5032.7735251913755, 5228.196272286055, 5256.553192978639, 2758.0622588320066, 3029.33354661974, 3032.7118923702656, 3051.7711171662213, 3072.7541796656215, 5473.343198816167, 5720.570957178228, 6126.571140020245, 6149.538784591084, 6196.721147548371, 5205.167173252262, 5309.814156504581, 5593.664380137205, 5665.291838520185, 6192.287886892975, 6054.788082417094, 6145.508359331243, 6163.260408750889, 6172.8765424691255, 6185.690715464209, 4756.7145971241835, 5407.945450637088, 5769.384572319757, 5798.594098413239, 5841.620294680833, 5236.455245702451, 5382.03123437785, 5517.69652669091, 5607.80372686954, 5772.643428794146, 5264.420913699413, 6012.1582978382185, 6026.403003204242, 6047.155617536693, 6063.302720187058, 3616.6202548732003, 3974.7813870237414, 4066.2070896176147, 4563.703359281769, 4619.699719518355, 5382.757775900172, 5561.675913072203, 5717.285339332583, 5750.367459579359, 5825.41745825431, 4807.519248075165, 5588.6646801192055, 5606.430199544134, 5613.859653508695, 5642.548596112383, 4673.906521869611, 5642.689652069175, 5724.599278050497, 5731.627444216113, 5733.3406658234835, 4899.069176856528, 5062.696238225718, 5298.394215605664, 5333.7333133343955, 5468.496163554895, 4697.953020469751, 4928.457869634266, 5371.650842695212, 5500.834974950758, 5760.423957604206, 5016.272640467185, 5081.644284900021, 5171.3018742469285, 5390.434004429635, 5442.727863606883], "value": 4292.759852067221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.9554831161821, 706.9081019467466, 759.7796638974697, 822.1613953767157, 823.5758584329071, 15074.24321828753, 16059.437919672813, 16343.673126537491, 16403.83596164038, 16404.425845095426, 14535.0, 15233.162176080312, 15318.923405382957, 15400.30698618562, 15434.536963891072, 923.796764711764, 938.6198589571235, 939.1359948847042, 944.9858252126223, 945.9952700236489, 773.1958762886591, 832.5379414424991, 940.6002448959199, 942.1379437814392, 966.1401352796017, 1002.3484734922285, 1003.9849402258972, 1004.3521928356206, 1005.2661557063311, 1007.1036777268237, 1000.0799264676515, 1000.8298589239847, 1001.1240164855751, 1001.4592120255018, 1002.3084071990303, 737.1743647115212, 737.3584981066507, 738.0810890441435, 739.3419856327872, 739.7263012685341, 15756.76364854528, 15952.361905523765, 16306.592335191597, 16652.16739163037, 16939.40712075084, 13872.861271387359, 13988.72022559543, 14051.367688454044, 14110.506132285424, 14149.434022639081, 11702.122340824388, 11782.823257651142, 11806.40967951599, 11829.231099978473, 12277.815832762515, 3265.5591495148087, 3574.4995700601676, 3575.6603122703564, 3605.242898991215, 3660.1764602964386, 112938.0, 113765.0, 117208.0, 118005.04987375299, 118464.40767796192, 9886.95056524727, 9960.0, 10163.7459063524, 10193.592256309812, 10436.0, 19528.31650892212, 19667.409977700692, 19960.20159193616, 20086.09612567438, 20098.39704808858, 7648.80877978055, 7653.11989121247, 7657.885131723028, 7733.0, 7808.726694565663, 4231.809568569422, 4275.978620106879, 4337.868920053451, 4351.0, 4534.682572219913, 3399.5240666306736, 3439.105832483572, 3455.170759017868, 3475.1659601695915, 3495.4232551629207, 493.51881915133174, 628.2398298059308, 643.1510406263687, 643.810076027573, 649.8602800397842, 683.8461346197066, 689.1420181873599, 702.217028013761, 716.8458781362032, 738.117949050879, 8603.300506513366, 9774.657886973922, 10129.848052279222, 10237.641682541076, 10367.222458315657, 573.8938296415176, 642.3287664390723, 687.700850130195, 711.1714852197132, 719.7732714195116, 4779.737114458669, 5001.0, 5013.874653133701, 5025.949740502549, 5030.446650868402, 88397.55801220953, 96531.51734241284, 101055.49472252592, 104248.47875760573, 106288.46855765671, 52022.0, 61889.69055154786, 65383.0, 78553.21446785459, 88644.55677721699, 66652.0, 66749.0, 71568.64215678888, 72336.0, 78838.0, 9809.705708828462, 10836.458177091272, 10934.627986893542, 11249.943750281524, 11262.324260544394, 47685.76157119192, 48113.0, 48826.0, 50198.74900625474, 50591.73520662013, 44806.0, 50306.245406319664, 62822.685886570274, 64890.0, 64965.0, 12566.685832854251, 12586.307753073668, 12773.361331933553, 12878.806817897552, 12892.355382231039, 12075.396230188631, 14274.8966273104, 14449.49426770058, 14504.202268875313, 14572.489962851248, 13486.86513134856, 13595.388207530486, 13620.31898405056, 13754.31228438594, 14025.018248722492, 13290.20258784476, 13708.0, 13779.517716879862, 14042.859571404564, 14325.85674143287, 10652.0, 12064.698382540506, 12552.246865188117, 12614.684632883884, 13167.078304518975, 10668.359898406121, 10710.57157713714, 11056.778864422828, 11070.096074283014, 11201.495932682887, 9818.75453113678, 10967.341959482457, 10985.137813456306, 11153.72115697114, 11201.047910927662], "value": 7491.864023198825, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 120.0, 120.0, 119.0, 120.0, 121.0, 118.0, 119.0, 121.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 151.0, 98.0, 135.0, 136.0, 100.0, 152.0, 141.0, 161.0, 145.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 201.0, 208.0, 199.0, 204.0, 200.0, 201.0, 202.0, 215.0, 283.0], "value": 211.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 62.0, 59.0, 87.0, 64.0, 60.0, 62.0, 60.0, 96.0, 81.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 74.0, 72.0, 70.0, 78.0, 80.0, 99.0, 102.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 105.0, 133.0, 128.0, 118.0, 133.0, 92.0, 78.0, 104.0, 135.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 97.0, 98.0, 99.0, 125.0, 99.0, 148.0, 117.0], "value": 107.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 93.0, 93.0, 82.0, 141.0, 147.0, 123.0, 141.0, 144.0], "value": 114.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 69.0, 66.0, 67.0, 68.0, 67.0, 67.0], "value": 66.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 62.0, 64.0, 63.0, 75.0, 84.0, 81.0, 59.0, 59.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 85.0, 87.0, 87.0, 84.0, 90.0, 85.0, 73.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 155.0, 149.0, 148.0, 140.0, 137.0, 143.0, 139.0, 203.0, 179.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 227.0, 214.0, 188.0, 168.0, 168.0, 188.0, 209.0, 176.0, 170.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 85.0, 86.0, 86.0, 81.0, 66.0, 69.0, 93.0, 81.0], "value": 79.3, "unit": "score"}]}]} 09:17:39 INFO - Return code: 0 09:17:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:17:39 ERROR - # TBPL SUCCESS # 09:17:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:39 INFO - 2017-03-07 09:17:39.714 screenresolution[1655:10677] starting screenresolution argv=screenresolution get 09:17:39 INFO - 2017-03-07 09:17:39.717 screenresolution[1655:10677] Display 0: 1600x1200x32@60 09:17:39 INFO - 2017-03-07 09:17:39.723 screenresolution[1656:10680] starting screenresolution argv=screenresolution list 09:17:39 INFO - Available Modes on Display 0 09:17:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:39 INFO - Intel Iris: 09:17:39 INFO - Chipset Model: Intel Iris 09:17:39 INFO - Type: GPU 09:17:39 INFO - Bus: Built-In 09:17:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:39 INFO - Vendor: Intel (0x8086) 09:17:39 INFO - Device ID: 0x0a2e 09:17:39 INFO - Revision ID: 0x0009 09:17:39 INFO - Displays: 09:17:39 INFO - Display: 09:17:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:39 INFO - Display Serial Number: 1600x1200 60 09:17:39 INFO - Main Display: Yes 09:17:39 INFO - Mirror: Off 09:17:39 INFO - Online: Yes 09:17:39 INFO - Rotation: Supported 09:17:39 INFO - Return code: 0 09:17:39 INFO - Running post-action listener: _resource_record_post_action 09:17:39 INFO - [mozharness: 2017-03-07 17:17:39.959296Z] Finished run-tests step (success) 09:17:39 INFO - Running post-run listener: _resource_record_post_run 09:17:40 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 40347648; Write bytes: 513518592; Read time: 389; Write time: 1606 09:17:40 INFO - TinderboxPrint: CPU usage
54.8% 09:17:40 INFO - TinderboxPrint: I/O read bytes / time
40,347,648 / 389 09:17:40 INFO - TinderboxPrint: I/O write bytes / time
513,518,592 / 1,606 09:17:40 INFO - TinderboxPrint: CPU idle
2,173.2 (45.2%) 09:17:40 INFO - TinderboxPrint: CPU system
969.6 (20.2%) 09:17:40 INFO - TinderboxPrint: CPU user
1,662.9 (34.6%) 09:17:40 INFO - TinderboxPrint: Swap in / out
249,036,800 / 0 09:17:40 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 205722112; Write bytes: 202135040; Read time: 15699; Write time: 447 09:17:40 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 34091008; Write bytes: 301446656; Read time: 309; Write time: 1127 09:17:40 INFO - Running post-run listener: _upload_blobber_files 09:17:40 INFO - Blob upload gear active. 09:17:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:17:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:17:40 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'] 09:17:40 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 09:17:40 INFO - (blobuploader) - INFO - Open directory for files ... 09:17:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:17:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:41 INFO - (blobuploader) - INFO - Done attempting. 09:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:43 INFO - (blobuploader) - INFO - Done attempting. 09:17:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:17:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:44 INFO - (blobuploader) - INFO - Done attempting. 09:17:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:17:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:46 INFO - (blobuploader) - INFO - Done attempting. 09:17:46 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:46 INFO - Return code: 0 09:17:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55375ec3aca8d26a3b57ef72be609ee3f94e39b129ec4e84a3987c14eec81fde7bc3e2825f8a721e1f8006554823470144ec6143a6e81ebdc351bc789aec4feb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a41eeda88509d12e4a08be0979c12c264311cf5a33cbde0b85a3edfc32f1c815093f4b84b805c302599891bec1f42f93093c4ed928168265416bc8d37f469641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06c26f13ef264d6bfce4f38aa163350d2b901a6b92c9ec7714a9d2d4c1a3bf8e2d3d722ab7b5bf0cbd9e2538df4d5040fe3e11bbcc5fd86d0a764d7c9f125996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0613816af22870f9d0f399da519b9faca99115a8bf961e28a71a9a4973bac2ec5802924d55f24056451a8cb5e4e93e421f48e8e8f68488aa99e7fed079660071"} 09:17:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:46 INFO - Contents: 09:17:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55375ec3aca8d26a3b57ef72be609ee3f94e39b129ec4e84a3987c14eec81fde7bc3e2825f8a721e1f8006554823470144ec6143a6e81ebdc351bc789aec4feb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a41eeda88509d12e4a08be0979c12c264311cf5a33cbde0b85a3edfc32f1c815093f4b84b805c302599891bec1f42f93093c4ed928168265416bc8d37f469641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06c26f13ef264d6bfce4f38aa163350d2b901a6b92c9ec7714a9d2d4c1a3bf8e2d3d722ab7b5bf0cbd9e2538df4d5040fe3e11bbcc5fd86d0a764d7c9f125996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0613816af22870f9d0f399da519b9faca99115a8bf961e28a71a9a4973bac2ec5802924d55f24056451a8cb5e4e93e421f48e8e8f68488aa99e7fed079660071"} 09:17:46 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:46 INFO - Copying logs to upload dir... 09:17:46 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.172795 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-07 09:17:46.608326) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 09:17:46.611998) ========= 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.4erMSFfEsF/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.KYiikBRzpy/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/55375ec3aca8d26a3b57ef72be609ee3f94e39b129ec4e84a3987c14eec81fde7bc3e2825f8a721e1f8006554823470144ec6143a6e81ebdc351bc789aec4feb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a41eeda88509d12e4a08be0979c12c264311cf5a33cbde0b85a3edfc32f1c815093f4b84b805c302599891bec1f42f93093c4ed928168265416bc8d37f469641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06c26f13ef264d6bfce4f38aa163350d2b901a6b92c9ec7714a9d2d4c1a3bf8e2d3d722ab7b5bf0cbd9e2538df4d5040fe3e11bbcc5fd86d0a764d7c9f125996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0613816af22870f9d0f399da519b9faca99115a8bf961e28a71a9a4973bac2ec5802924d55f24056451a8cb5e4e93e421f48e8e8f68488aa99e7fed079660071"} build_url:https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011210 build_url: 'https://queue.taskcluster.net/v1/task/LqKE7MyYSzOPYogQqz6ejA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/55375ec3aca8d26a3b57ef72be609ee3f94e39b129ec4e84a3987c14eec81fde7bc3e2825f8a721e1f8006554823470144ec6143a6e81ebdc351bc789aec4feb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a41eeda88509d12e4a08be0979c12c264311cf5a33cbde0b85a3edfc32f1c815093f4b84b805c302599891bec1f42f93093c4ed928168265416bc8d37f469641", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/06c26f13ef264d6bfce4f38aa163350d2b901a6b92c9ec7714a9d2d4c1a3bf8e2d3d722ab7b5bf0cbd9e2538df4d5040fe3e11bbcc5fd86d0a764d7c9f125996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0613816af22870f9d0f399da519b9faca99115a8bf961e28a71a9a4973bac2ec5802924d55f24056451a8cb5e4e93e421f48e8e8f68488aa99e7fed079660071"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 09:17:46.645874) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:17:46.646190) ========= 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.4erMSFfEsF/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.KYiikBRzpy/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.005156 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:17:46.694353) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 09:17:46.694671) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 09:17:46.694986) ========= ========= Total master_lag: 0.18 =========