builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0122 starttime: 1491490922.61 results: success (0) buildid: 20170406071933 builduid: 2cf52192536141cca0762961ca740b8e revision: e03c56f3a839d1575e87dbd782638ad4894429e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.606782) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.607681) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.608168) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.635501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.635833) ========= 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.CE7PLRABiz/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.o9XslWGTj6/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-06 08:02:02-- 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% 168M=0s 2017-04-06 08:02:02 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.193803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.853664) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.853987) ========= 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.CE7PLRABiz/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.o9XslWGTj6/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.029842 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:02.907368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 08:02:02.908046) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e03c56f3a839d1575e87dbd782638ad4894429e8 --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 e03c56f3a839d1575e87dbd782638ad4894429e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CE7PLRABiz/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.o9XslWGTj6/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-06 08:02:03,013 truncating revision to first 12 chars 2017-04-06 08:02:03,013 Setting DEBUG logging. 2017-04-06 08:02:03,013 attempt 1/10 2017-04-06 08:02:03,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e03c56f3a839?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 08:02:05,101 unpacking tar archive at: mozilla-aurora-e03c56f3a839/testing/mozharness/ program finished with exit code 0 elapsedTime=2.351383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-06 08:02:05.284038) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:05.284414) ========= script_repo_revision: e03c56f3a839d1575e87dbd782638ad4894429e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:05.284854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:05.285191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 08:02:05.305208) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-06 08:02:05.305523) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.CE7PLRABiz/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.o9XslWGTj6/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:02:05 INFO - MultiFileLogger online at 20170406 08:02:05 in /builds/slave/test 08:02:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:02:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:02:05 INFO - {'append_to_log': False, 08:02:05 INFO - 'base_work_dir': '/builds/slave/test', 08:02:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:02:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:02:05 INFO - 'branch': 'Mozilla-Aurora', 08:02:05 INFO - 'buildbot_json_path': 'buildprops.json', 08:02:05 INFO - 'config_files': ('talos/mac_config.py',), 08:02:05 INFO - 'default_actions': ('clobber', 08:02:05 INFO - 'read-buildbot-config', 08:02:05 INFO - 'download-and-extract', 08:02:05 INFO - 'populate-webroot', 08:02:05 INFO - 'create-virtualenv', 08:02:05 INFO - 'install', 08:02:05 INFO - 'run-tests'), 08:02:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:02:05 INFO - 'download_minidump_stackwalk': True, 08:02:05 INFO - 'download_symbols': 'ondemand', 08:02:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:02:05 INFO - 'tooltool.py': '/tools/tooltool.py', 08:02:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:02:05 INFO - '/tools/misc-python/virtualenv.py')}, 08:02:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:02:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:02:05 INFO - 'gecko_profile': False, 08:02:05 INFO - 'gecko_profile_interval': 0, 08:02:05 INFO - 'installer_path': 'installer.exe', 08:02:05 INFO - 'log_level': 'info', 08:02:05 INFO - 'log_name': 'talos', 08:02:05 INFO - 'log_to_console': True, 08:02:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:02:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:02:05 INFO - 'opt_config_files': (), 08:02:05 INFO - 'pip_index': False, 08:02:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:02:05 INFO - 'cmd': ('bash', 08:02:05 INFO - '-c', 08:02:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:02:05 INFO - 'enabled': True, 08:02:05 INFO - 'halt_on_failure': False, 08:02:05 INFO - 'name': 'check_screen_resolution'},), 08:02:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:02:05 INFO - 'cmd': ('bash', 08:02:05 INFO - '-c', 08:02:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:02:05 INFO - 'enabled': True, 08:02:05 INFO - 'halt_on_failure': False, 08:02:05 INFO - 'name': 'check_screen_resolution'},), 08:02:05 INFO - 'run_cmd_checks_enabled': True, 08:02:05 INFO - 'suite': 'dromaeojs', 08:02:05 INFO - 'system_bits': '32', 08:02:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:02:05 INFO - 'title': 't-yosemite-r7-0122', 08:02:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:02:05 INFO - 'use_talos_json': True, 08:02:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:02:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:02:05 INFO - 'work_dir': 'build'} 08:02:05 INFO - [mozharness: 2017-04-06 15:02:05.518653Z] Running clobber step. 08:02:05 INFO - Running pre-action listener: _resource_record_pre_action 08:02:05 INFO - Running main action method: clobber 08:02:05 INFO - rmtree: /builds/slave/test/build 08:02:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:02:08 INFO - Running post-action listener: _resource_record_post_action 08:02:08 INFO - [mozharness: 2017-04-06 15:02:08.068738Z] Finished clobber step (success) 08:02:08 INFO - [mozharness: 2017-04-06 15:02:08.069005Z] Running read-buildbot-config step. 08:02:08 INFO - Running pre-action listener: _resource_record_pre_action 08:02:08 INFO - Running main action method: read_buildbot_config 08:02:08 INFO - Using buildbot properties: 08:02:08 INFO - { 08:02:08 INFO - "project": "", 08:02:08 INFO - "product": "firefox", 08:02:08 INFO - "script_repo_revision": "production", 08:02:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:02:08 INFO - "repository": "", 08:02:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:02:08 INFO - "buildid": "20170406071933", 08:02:08 INFO - "pgo_build": "False", 08:02:08 INFO - "basedir": "/builds/slave/test", 08:02:08 INFO - "buildnumber": 43, 08:02:08 INFO - "slavename": "t-yosemite-r7-0122", 08:02:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:02:08 INFO - "platform": "yosemite_r7", 08:02:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:02:08 INFO - "branch": "mozilla-aurora", 08:02:08 INFO - "repo_path": "releases/mozilla-aurora", 08:02:08 INFO - "revision": "e03c56f3a839d1575e87dbd782638ad4894429e8", 08:02:08 INFO - "stage_platform": "macosx64", 08:02:08 INFO - "builduid": "2cf52192536141cca0762961ca740b8e", 08:02:08 INFO - "slavebuilddir": "test" 08:02:08 INFO - } 08:02:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 08:02:08 INFO - Running post-action listener: _resource_record_post_action 08:02:08 INFO - [mozharness: 2017-04-06 15:02:08.074793Z] Finished read-buildbot-config step (success) 08:02:08 INFO - [mozharness: 2017-04-06 15:02:08.075007Z] Running download-and-extract step. 08:02:08 INFO - Running pre-action listener: _resource_record_pre_action 08:02:08 INFO - Running main action method: download_and_extract 08:02:08 INFO - mkdir: /builds/slave/test/build/tests 08:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:08 INFO - trying https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 08:02:08 INFO - Downloading https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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:02:09 INFO - Downloaded 1199 bytes. 08:02:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 08:02:09 INFO - Using the following test package requirements: 08:02:09 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:02:09 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 08:02:09 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 08:02:09 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'jsshell-mac.zip'], 08:02:09 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 08:02:09 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 08:02:09 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 08:02:09 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 08:02:09 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 08:02:09 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 08:02:09 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 08:02:09 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:02:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:02:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:02:09 INFO - Fetch https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:02:10 INFO - Expected file size: 51790423 08:02:10 INFO - Obtained file size: 51790423 08:02:26 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:02:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 08:02:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:02:26 INFO - Fetch https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 08:02:28 INFO - Expected file size: 51790423 08:02:28 INFO - Obtained file size: 51790423 08:02:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 08:02:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:02:43 INFO - Fetch https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 08:02:44 INFO - Expected file size: 14404438 08:02:44 INFO - Obtained file size: 14404438 08:02:45 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:02:45 INFO - trying https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:02:45 INFO - Downloading https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 08:02:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/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:02:47 INFO - Downloaded 74687087 bytes. 08:02:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:02:47 INFO - mkdir: /builds/slave/test/properties 08:02:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:47 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:47 INFO - Contents: 08:02:47 INFO - build_url:https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 08:02:48 INFO - Running post-action listener: _resource_record_post_action 08:02:48 INFO - Running post-action listener: set_extra_try_arguments 08:02:48 INFO - [mozharness: 2017-04-06 15:02:48.239442Z] Finished download-and-extract step (success) 08:02:48 INFO - [mozharness: 2017-04-06 15:02:48.239547Z] Running populate-webroot step. 08:02:48 INFO - Running pre-action listener: _resource_record_pre_action 08:02:48 INFO - Running main action method: populate_webroot 08:02:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:48 INFO - '--tppagecycles', 08:02:48 INFO - '7'], 08:02:48 INFO - 'tests': ['tsvgm']}}, 08:02:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:02:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:02:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['damp', 'tps']}, 08:02:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'tests': ['damp', 'tps']}, 08:02:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['dromaeo_dom']}, 08:02:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['basic_compositor_video']}, 08:02:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:02:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['a11yr', 08:02:48 INFO - 'ts_paint', 08:02:48 INFO - 'tpaint', 08:02:48 INFO - 'sessionrestore', 08:02:48 INFO - 'sessionrestore_no_auto_restore', 08:02:48 INFO - 'tabpaint']}, 08:02:48 INFO - 'other-e10s': {'tests': ['a11yr', 08:02:48 INFO - 'ts_paint', 08:02:48 INFO - 'tpaint', 08:02:48 INFO - 'sessionrestore', 08:02:48 INFO - 'sessionrestore_no_auto_restore', 08:02:48 INFO - 'tabpaint']}, 08:02:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:02:48 INFO - 'ts_paint', 08:02:48 INFO - 'tpaint', 08:02:48 INFO - 'sessionrestore', 08:02:48 INFO - 'sessionrestore_no_auto_restore', 08:02:48 INFO - 'tabpaint']}, 08:02:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:02:48 INFO - 'ts_paint', 08:02:48 INFO - 'tpaint', 08:02:48 INFO - 'sessionrestore', 08:02:48 INFO - 'sessionrestore_no_auto_restore', 08:02:48 INFO - 'tabpaint']}, 08:02:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['a11yr', 08:02:48 INFO - 'ts_paint', 08:02:48 INFO - 'tpaint', 08:02:48 INFO - 'sessionrestore', 08:02:48 INFO - 'sessionrestore_no_auto_restore', 08:02:48 INFO - 'tabpaint']}, 08:02:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['a11yr', 08:02:48 INFO - 'ts_paint', 08:02:48 INFO - 'tpaint', 08:02:48 INFO - 'sessionrestore', 08:02:48 INFO - 'sessionrestore_no_auto_restore', 08:02:48 INFO - 'tabpaint']}, 08:02:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['tsvgx', 08:02:48 INFO - 'tsvgr_opacity', 08:02:48 INFO - 'tart', 08:02:48 INFO - 'tscrollx', 08:02:48 INFO - 'cart', 08:02:48 INFO - 'tsvg_static']}, 08:02:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:02:48 INFO - 'tsvgr_opacity', 08:02:48 INFO - 'tart', 08:02:48 INFO - 'tscrollx', 08:02:48 INFO - 'cart', 08:02:48 INFO - 'tsvg_static']}, 08:02:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'talos_options': ['--disable-e10s'], 08:02:48 INFO - 'tests': ['tp5o']}, 08:02:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:02:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'talos_options': ['--disable-e10s', 08:02:48 INFO - '--xperf_path', 08:02:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:48 INFO - 'tests': ['tp5n']}, 08:02:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:02:48 INFO - 'talos_options': ['--xperf_path', 08:02:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:48 INFO - 'tests': ['tp5n']}}, 08:02:48 INFO - 'talos.zip': {'path': '', 08:02:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:48 INFO - Running post-action listener: _resource_record_post_action 08:02:48 INFO - [mozharness: 2017-04-06 15:02:48.258432Z] Finished populate-webroot step (success) 08:02:48 INFO - [mozharness: 2017-04-06 15:02:48.258562Z] Running create-virtualenv step. 08:02:48 INFO - Running pre-action listener: _resource_record_pre_action 08:02:48 INFO - Running main action method: create_virtualenv 08:02:48 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:02:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:48 INFO - Using real prefix '/tools/python27' 08:02:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:49 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:51 INFO - Installing pip.................done. 08:02:51 INFO - Return code: 0 08:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:02:52 INFO - Reading from file tmpfile_stdout 08:02:52 INFO - Output received: 08:02:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:02:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:52 INFO - 'HOME': '/Users/cltbld', 08:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:52 INFO - 'LOGNAME': 'cltbld', 08:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:52 INFO - 'MOZ_NO_REMOTE': '1', 08:02:52 INFO - 'NO_EM_RESTART': '1', 08:02:52 INFO - 'PAGER': '/bin/cat', 08:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:52 INFO - 'PWD': '/builds/slave/test', 08:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:52 INFO - 'SHELL': '/bin/bash', 08:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:02:52 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:52 INFO - 'USER': 'cltbld', 08:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:52 INFO - 'XPC_FLAGS': '0x0', 08:02:52 INFO - 'XPC_SERVICE_NAME': '0', 08:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:52 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:02:52 INFO - Cleaning up... 08:02:52 INFO - Return code: 0 08:02:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:02:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:02:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:52 INFO - 'HOME': '/Users/cltbld', 08:02:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:52 INFO - 'LOGNAME': 'cltbld', 08:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:52 INFO - 'MOZ_NO_REMOTE': '1', 08:02:52 INFO - 'NO_EM_RESTART': '1', 08:02:52 INFO - 'PAGER': '/bin/cat', 08:02:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:52 INFO - 'PWD': '/builds/slave/test', 08:02:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:52 INFO - 'SHELL': '/bin/bash', 08:02:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:02:52 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:02:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:52 INFO - 'USER': 'cltbld', 08:02:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:52 INFO - 'XPC_FLAGS': '0x0', 08:02:52 INFO - 'XPC_SERVICE_NAME': '0', 08:02:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:52 INFO - Downloading/unpacking psutil>=3.1.1 08:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:56 INFO - Installing collected packages: psutil 08:02:56 INFO - Running setup.py install for psutil 08:02:56 INFO - building 'psutil._psutil_osx' extension 08:02:56 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:02:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:02:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:02:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:02:57 INFO - building 'psutil._psutil_posix' extension 08:02:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:02:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:02:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:02:57 INFO - ^ 08:02:57 INFO - 1 warning generated. 08:02:57 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:02:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:57 INFO - Successfully installed psutil 08:02:57 INFO - Cleaning up... 08:02:57 INFO - Return code: 0 08:02:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:57 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 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:02:57 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:02:57 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:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:57 INFO - 'HOME': '/Users/cltbld', 08:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:57 INFO - 'LOGNAME': 'cltbld', 08:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:57 INFO - 'MOZ_NO_REMOTE': '1', 08:02:57 INFO - 'NO_EM_RESTART': '1', 08:02:57 INFO - 'PAGER': '/bin/cat', 08:02:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:57 INFO - 'PWD': '/builds/slave/test', 08:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:57 INFO - 'SHELL': '/bin/bash', 08:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:02:57 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:57 INFO - 'USER': 'cltbld', 08:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:57 INFO - 'XPC_FLAGS': '0x0', 08:02:57 INFO - 'XPC_SERVICE_NAME': '0', 08:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:02:57 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:02:57 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:02:57 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:02:57 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:02:57 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:02:57 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:03:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:03:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:03:00 INFO - Installing collected packages: mozsystemmonitor 08:03:00 INFO - Running setup.py install for mozsystemmonitor 08:03:00 INFO - Successfully installed mozsystemmonitor 08:03:00 INFO - Cleaning up... 08:03:00 INFO - Return code: 0 08:03:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:03:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:03:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:01 INFO - 'HOME': '/Users/cltbld', 08:03:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:01 INFO - 'LOGNAME': 'cltbld', 08:03:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:01 INFO - 'MOZ_NO_REMOTE': '1', 08:03:01 INFO - 'NO_EM_RESTART': '1', 08:03:01 INFO - 'PAGER': '/bin/cat', 08:03:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:01 INFO - 'PWD': '/builds/slave/test', 08:03:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:01 INFO - 'SHELL': '/bin/bash', 08:03:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:01 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:01 INFO - 'USER': 'cltbld', 08:03:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:01 INFO - 'XPC_FLAGS': '0x0', 08:03:01 INFO - 'XPC_SERVICE_NAME': '0', 08:03:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:01 INFO - Downloading/unpacking jsonschema==2.5.1 08:03:01 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:03:01 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:03:01 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:03:01 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:03:01 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:03:01 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:03:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:03:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:05 INFO - Downloading functools32-3.2.3-2.tar.gz 08:03:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:03:05 INFO - warning: no files found matching '*.txt' 08:03:05 INFO - no previously-included directories found matching 'build' 08:03:05 INFO - no previously-included directories found matching 'dist' 08:03:05 INFO - no previously-included directories found matching '.git*' 08:03:05 INFO - Installing collected packages: jsonschema, functools32 08:03:05 INFO - Running setup.py install for functools32 08:03:05 INFO - warning: no files found matching '*.txt' 08:03:05 INFO - no previously-included directories found matching 'build' 08:03:05 INFO - no previously-included directories found matching 'dist' 08:03:05 INFO - no previously-included directories found matching '.git*' 08:03:05 INFO - Successfully installed jsonschema functools32 08:03:05 INFO - Cleaning up... 08:03:05 INFO - Return code: 0 08:03:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:05 INFO - 'HOME': '/Users/cltbld', 08:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:05 INFO - 'LOGNAME': 'cltbld', 08:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:05 INFO - 'MOZ_NO_REMOTE': '1', 08:03:05 INFO - 'NO_EM_RESTART': '1', 08:03:05 INFO - 'PAGER': '/bin/cat', 08:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:05 INFO - 'PWD': '/builds/slave/test', 08:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:05 INFO - 'SHELL': '/bin/bash', 08:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:05 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:05 INFO - 'USER': 'cltbld', 08:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:05 INFO - 'XPC_FLAGS': '0x0', 08:03:05 INFO - 'XPC_SERVICE_NAME': '0', 08:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:03:05 INFO - Cleaning up... 08:03:05 INFO - Return code: 0 08:03:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:05 INFO - 'HOME': '/Users/cltbld', 08:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:05 INFO - 'LOGNAME': 'cltbld', 08:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:05 INFO - 'MOZ_NO_REMOTE': '1', 08:03:05 INFO - 'NO_EM_RESTART': '1', 08:03:05 INFO - 'PAGER': '/bin/cat', 08:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:05 INFO - 'PWD': '/builds/slave/test', 08:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:05 INFO - 'SHELL': '/bin/bash', 08:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:05 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:05 INFO - 'USER': 'cltbld', 08:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:05 INFO - 'XPC_FLAGS': '0x0', 08:03:05 INFO - 'XPC_SERVICE_NAME': '0', 08:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:06 INFO - Downloading/unpacking blobuploader==1.2.4 08:03:06 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:03:06 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:03:06 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:03:06 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:03:06 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:03:06 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:03:08 INFO - Downloading blobuploader-1.2.4.tar.gz 08:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:03:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:03:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:10 INFO - Downloading docopt-0.6.1.tar.gz 08:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:03:10 INFO - Installing collected packages: blobuploader, requests, docopt 08:03:10 INFO - Running setup.py install for blobuploader 08:03:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:03:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:03:10 INFO - Running setup.py install for requests 08:03:10 INFO - Running setup.py install for docopt 08:03:10 INFO - Successfully installed blobuploader requests docopt 08:03:10 INFO - Cleaning up... 08:03:11 INFO - Return code: 0 08:03:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:03:11 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:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:11 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:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:11 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 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:11 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:03:11 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:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:11 INFO - 'HOME': '/Users/cltbld', 08:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:11 INFO - 'LOGNAME': 'cltbld', 08:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:11 INFO - 'MOZ_NO_REMOTE': '1', 08:03:11 INFO - 'NO_EM_RESTART': '1', 08:03:11 INFO - 'PAGER': '/bin/cat', 08:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:11 INFO - 'PWD': '/builds/slave/test', 08:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:11 INFO - 'SHELL': '/bin/bash', 08:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:11 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:11 INFO - 'USER': 'cltbld', 08:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:11 INFO - 'XPC_FLAGS': '0x0', 08:03:11 INFO - 'XPC_SERVICE_NAME': '0', 08:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ZRcRLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-YV5d6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-xh89Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-6H1Fpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-m58BQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-sVSV7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:03:11 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-CoA0I4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BjJ_Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-pzgWzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-HSpyt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Nj0XNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_fEGko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5YZ6wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-gyRLHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-sb5p_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-rj806V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:03:12 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-LfFoUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:03:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:03:13 INFO - Running setup.py install for manifestparser 08:03:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:03:13 INFO - Running setup.py install for mozcrash 08:03:13 INFO - Running setup.py install for mozdebug 08:03:13 INFO - Running setup.py install for mozdevice 08:03:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:03:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:03:13 INFO - Running setup.py install for mozfile 08:03:13 INFO - Running setup.py install for mozhttpd 08:03:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Running setup.py install for mozinfo 08:03:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Running setup.py install for mozInstall 08:03:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Running setup.py install for mozleak 08:03:14 INFO - Running setup.py install for mozlog 08:03:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Running setup.py install for moznetwork 08:03:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:03:14 INFO - Running setup.py install for mozprocess 08:03:14 INFO - Running setup.py install for mozprofile 08:03:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:03:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:03:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:03:15 INFO - Running setup.py install for mozrunner 08:03:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:03:15 INFO - Running setup.py install for mozscreenshot 08:03:15 INFO - Running setup.py install for moztest 08:03:15 INFO - Running setup.py install for mozversion 08:03:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:03:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:03:15 INFO - Cleaning up... 08:03:15 INFO - Return code: 0 08:03:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:03: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:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03: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:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:15 INFO - 'HOME': '/Users/cltbld', 08:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:15 INFO - 'LOGNAME': 'cltbld', 08:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:15 INFO - 'MOZ_NO_REMOTE': '1', 08:03:15 INFO - 'NO_EM_RESTART': '1', 08:03:15 INFO - 'PAGER': '/bin/cat', 08:03:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:15 INFO - 'PWD': '/builds/slave/test', 08:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:15 INFO - 'SHELL': '/bin/bash', 08:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:15 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:15 INFO - 'USER': 'cltbld', 08:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:15 INFO - 'XPC_FLAGS': '0x0', 08:03:15 INFO - 'XPC_SERVICE_NAME': '0', 08:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:03:15 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-AEdAYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:03:16 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:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-mm_O6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:03:16 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:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_RumeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-oHL9o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-QumscS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5glTsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-3DdZxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-YLvN8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-e0e_OR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:03:16 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-LeYu_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:03:17 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:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Xrkj4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:03:17 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:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-cYdCpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-NZzk_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-r9eNNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-TX0GjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-6Ut38d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:03:17 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ki0WX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:03:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:20 INFO - Downloading blessings-1.6.tar.gz 08:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:03:20 INFO - Installing collected packages: blessings 08:03:20 INFO - Running setup.py install for blessings 08:03:20 INFO - Successfully installed blessings 08:03:20 INFO - Cleaning up... 08:03:20 INFO - Return code: 0 08:03:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:03:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:03:21 INFO - Reading from file tmpfile_stdout 08:03:21 INFO - Current package versions: 08:03:21 INFO - blessings == 1.6 08:03:21 INFO - blobuploader == 1.2.4 08:03:21 INFO - docopt == 0.6.1 08:03:21 INFO - functools32 == 3.2.3-2 08:03:21 INFO - jsonschema == 2.5.1 08:03:21 INFO - manifestparser == 1.1 08:03:21 INFO - mozInstall == 1.12 08:03:21 INFO - mozcrash == 1.0 08:03:21 INFO - mozdebug == 0.1 08:03:21 INFO - mozdevice == 0.49 08:03:21 INFO - mozfile == 1.2 08:03:21 INFO - mozhttpd == 0.7 08:03:21 INFO - mozinfo == 0.9 08:03:21 INFO - mozleak == 0.1 08:03:21 INFO - mozlog == 3.4 08:03:21 INFO - moznetwork == 0.27 08:03:21 INFO - mozprocess == 0.25 08:03:21 INFO - mozprofile == 0.28 08:03:21 INFO - mozrunner == 6.13 08:03:21 INFO - mozscreenshot == 0.1 08:03:21 INFO - mozsystemmonitor == 0.3 08:03:21 INFO - moztest == 0.8 08:03:21 INFO - mozversion == 1.4 08:03:21 INFO - psutil == 3.1.1 08:03:21 INFO - requests == 1.2.3 08:03:21 INFO - wsgiref == 0.1.2 08:03:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:21 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 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:21 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:03:21 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:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:21 INFO - 'HOME': '/Users/cltbld', 08:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:21 INFO - 'LOGNAME': 'cltbld', 08:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:21 INFO - 'MOZ_NO_REMOTE': '1', 08:03:21 INFO - 'NO_EM_RESTART': '1', 08:03:21 INFO - 'PAGER': '/bin/cat', 08:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:21 INFO - 'PWD': '/builds/slave/test', 08:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:21 INFO - 'SHELL': '/bin/bash', 08:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:21 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:21 INFO - 'USER': 'cltbld', 08:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:21 INFO - 'XPC_FLAGS': '0x0', 08:03:21 INFO - 'XPC_SERVICE_NAME': '0', 08:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:03:21 INFO - Cleaning up... 08:03:21 INFO - Return code: 0 08:03:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c17ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c17d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f79d81a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, '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.CE7PLRABiz/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.o9XslWGTj6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:21 INFO - 'HOME': '/Users/cltbld', 08:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:21 INFO - 'LOGNAME': 'cltbld', 08:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:21 INFO - 'MOZ_NO_REMOTE': '1', 08:03:21 INFO - 'NO_EM_RESTART': '1', 08:03:21 INFO - 'PAGER': '/bin/cat', 08:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:21 INFO - 'PWD': '/builds/slave/test', 08:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:21 INFO - 'SHELL': '/bin/bash', 08:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:21 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:21 INFO - 'USER': 'cltbld', 08:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:21 INFO - 'XPC_FLAGS': '0x0', 08:03:21 INFO - 'XPC_SERVICE_NAME': '0', 08:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:03:21 INFO - Cleaning up... 08:03:21 INFO - Return code: 0 08:03:21 INFO - Running post-action listener: _resource_record_post_action 08:03:21 INFO - Running post-action listener: _start_resource_monitoring 08:03:21 INFO - Starting resource monitoring. 08:03:21 INFO - [mozharness: 2017-04-06 15:03:21.677212Z] Finished create-virtualenv step (success) 08:03:21 INFO - [mozharness: 2017-04-06 15:03:21.678230Z] Running install step. 08:03:21 INFO - Running pre-action listener: _resource_record_pre_action 08:03:21 INFO - Running main action method: install 08:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:03:21 INFO - Reading from file tmpfile_stdout 08:03:21 INFO - Detecting whether we're running mozinstall >=1.0... 08:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:03:22 INFO - Reading from file tmpfile_stdout 08:03:22 INFO - Output received: 08:03:22 INFO - Usage: mozinstall [options] installer 08:03:22 INFO - Options: 08:03:22 INFO - -h, --help show this help message and exit 08:03:22 INFO - -d DEST, --destination=DEST 08:03:22 INFO - Directory to install application into. [default: 08:03:22 INFO - "/builds/slave/test"] 08:03:22 INFO - --app=APP Application being installed. [default: firefox] 08:03:22 INFO - mkdir: /builds/slave/test/build/application 08:03:22 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:03:22 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:03:42 INFO - Reading from file tmpfile_stdout 08:03:42 INFO - Output received: 08:03:42 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:03:42 INFO - Running post-action listener: _resource_record_post_action 08:03:42 INFO - [mozharness: 2017-04-06 15:03:42.539268Z] Finished install step (success) 08:03:42 INFO - [mozharness: 2017-04-06 15:03:42.539439Z] Running run-tests step. 08:03:42 INFO - Running pre-action listener: _resource_record_pre_action 08:03:42 INFO - Running main action method: run_tests 08:03:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:03:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:03:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:03:42 INFO - 2017-04-06 08:03:42.554 screenresolution[1623:7346] starting screenresolution argv=screenresolution get 08:03:42 INFO - 2017-04-06 08:03:42.556 screenresolution[1623:7346] Display 0: 1600x1200x32@60 08:03:42 INFO - 2017-04-06 08:03:42.562 screenresolution[1624:7349] starting screenresolution argv=screenresolution list 08:03:42 INFO - Available Modes on Display 0 08:03:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:03:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:03:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:03:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:03:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:03:42 INFO - Intel Iris: 08:03:42 INFO - Chipset Model: Intel Iris 08:03:42 INFO - Type: GPU 08:03:42 INFO - Bus: Built-In 08:03:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:03:42 INFO - Vendor: Intel (0x8086) 08:03:42 INFO - Device ID: 0x0a2e 08:03:42 INFO - Revision ID: 0x0009 08:03:42 INFO - Displays: 08:03:42 INFO - Display: 08:03:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:03:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:03:42 INFO - Display Serial Number: 1600x1200 60 08:03:42 INFO - Main Display: Yes 08:03:42 INFO - Mirror: Off 08:03:42 INFO - Online: Yes 08:03:42 INFO - Rotation: Supported 08:03:42 INFO - Return code: 0 08:03:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:42 INFO - Python 2.7.3 08:03:42 INFO - Return code: 0 08:03:42 INFO - grabbing minidump binary from tooltool 08:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf58d30>, '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 0x10be7fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:03:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:03:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:03:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:42 INFO - Return code: 0 08:03:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:03:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:42 INFO - ENV: RUST_BACKTRACE is now 1 08:03:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:03:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:42 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:03:42 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:03:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CE7PLRABiz/Render', 08:03:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:42 INFO - 'HOME': '/Users/cltbld', 08:03:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:42 INFO - 'LOGNAME': 'cltbld', 08:03:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:42 INFO - 'MOZ_NO_REMOTE': '1', 08:03:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:42 INFO - 'NO_EM_RESTART': '1', 08:03:42 INFO - 'PAGER': '/bin/cat', 08:03:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:42 INFO - 'PWD': '/builds/slave/test', 08:03:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:42 INFO - 'RUST_BACKTRACE': '1', 08:03:42 INFO - 'SHELL': '/bin/bash', 08:03:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o9XslWGTj6/Listeners', 08:03:42 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 08:03:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:42 INFO - 'USER': 'cltbld', 08:03:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:42 INFO - 'XPC_FLAGS': '0x0', 08:03:42 INFO - 'XPC_SERVICE_NAME': '0', 08:03:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:03:43 INFO - mozversion application_buildid: 20170406071933 08:03:43 INFO - mozversion application_changeset: e03c56f3a839d1575e87dbd782638ad4894429e8 08:03:43 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:03:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:43 INFO - mozversion application_name: Firefox 08:03:43 INFO - mozversion application_remotingname: firefox-dev 08:03:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:03:43 INFO - mozversion application_vendor: Mozilla 08:03:43 INFO - mozversion application_version: 54.0a2 08:03:43 INFO - mozversion platform_buildid: 20170406071933 08:03:43 INFO - mozversion platform_changeset: e03c56f3a839d1575e87dbd782638ad4894429e8 08:03:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:03:43 INFO - mozversion platform_version: 54.0a2 08:03:43 INFO - using testdate: 1491491023 08:03:43 INFO - actual date: 1491491023 08:03:43 INFO - starting webserver on 'localhost:49245' 08:03:43 INFO - SUITE-START | Running 2 tests 08:03:43 INFO - TEST-START | dromaeo_css 08:03:43 INFO - operating with platform_type : mac_ 08:03:43 INFO - Initialising browser for dromaeo_css test... 08:03:43 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpk7skix/profile http://localhost:49245/getInfo.html) 08:03:45 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:03:45 INFO - PROCESS | 1634 | colorDepth:24 08:03:45 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:03:45 INFO - PROCESS | 1634 | __metrics 08:03:45 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:03:45 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:03:46 INFO - TEST-INFO | 1634: exit 0 08:03:46 INFO - Browser initialized. 08:03:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:46 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpk7skix/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:58 INFO - PROCESS | 1636 | RSS: Main: 211238912 08:03:58 INFO - PROCESS | 1636 | 08:07:03 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 08:07:06 INFO - PROCESS | 1636 | RSS: Main: 435830784 08:07:06 INFO - PROCESS | 1636 | 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 08:08:49 INFO - PROCESS | 1636 | 08:08:49 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:08:49 INFO - PROCESS | 1636 | Full stack: 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 08:08:49 INFO - PROCESS | 1636 | 08:08:49 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:08:49 INFO - PROCESS | 1636 | Full stack: 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 08:08:49 INFO - PROCESS | 1636 | 08:08:49 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:08:49 INFO - PROCESS | 1636 | Full stack: 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | ************************* 08:08:49 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 08:08:49 INFO - PROCESS | 1636 | 08:08:49 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:08:49 INFO - PROCESS | 1636 | Full stack: 08:08:49 INFO - PROCESS | 1636 | ************************* 08:10:09 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 08:10:09 INFO - PROCESS | 1636 | RSS: Main: 351555584 08:10:09 INFO - PROCESS | 1636 | 08:13:12 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 08:13:12 INFO - PROCESS | 1636 | RSS: Main: 322146304 08:13:12 INFO - PROCESS | 1636 | 08:16:15 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 08:16:16 INFO - PROCESS | 1636 | RSS: Main: 324481024 08:16:16 INFO - PROCESS | 1636 | 08:19:19 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 08:19:20 INFO - PROCESS | 1636 | RSS: Main: 322506752 08:19:20 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 08:22:23 INFO - PROCESS | 1636 | RSS: Main: 510156800 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | __start_tp_report 08:22:23 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 08:22:23 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 08:22:23 INFO - PROCESS | 1636 | |i|pagename|runs| 08:22:23 INFO - PROCESS | 1636 | |0;dojo.html;2870.8277503349796;3413.8293085345736;3425.263568332808;3467;3477.1307173206683;5967.82096537104;6007.7596896124105;6016.368281330463;6024.54815888809;6060.393960603938;6007.519398448125;6103.633781973084;6124.693765311739;6130.9080363794465;6135.202423684926;5421.83734487965;5430.402655707869;5465.672059676413;5474.315710536183;5604.103343465046;5361.812336568221;5377.946220537784;5420.1327787554;5548.056830338832;5563.443655634424;5438.972805135969;5469.124940009599;5469.480399362054;5469.781208751665;5475.0966090595;4669.8832529186875;5481.451854814526;5491.478309560565;5492.340919089695;5495.2856128703415;5281.383990897945;5422.566194704415;5426.131818908995;5433.782648694048;5463.180522921593;6099.054646529795;6104.2980057293535;6113.266408031065;6113.510919126459;6113.991191453405;6393.073004414334;6415.775447859349;6423.807285781434;6432.970724684075;6455.870882582322;6418.454431373339;6495.805125846185;6547.738090476375;6566.146400967891;6576.112224849632;6133.693315334215;6201.069839524062;6211.25464944205;6212.502999760009;6223.25320961483;6351.96824015877;6658.500612454037;6669;6673.0991316172185;6679.998000299994;5212.947870521322;6338.144350512714;6352.872942541123;6369.20384951881;6369.840753981095;6508.9023664645065;6510.7721229756735;6521.804345869631;6523.3150519194805;6530.314316996647;6301.275353334334;6440.420362167334;6451.258105317855;6455.870882582322;6467.288598254188;5288.867778305573;6339.904901426482;6373.3945275198785;6436.549441539048;6457.160569125984;5199.792008319625;5204.583633309327;5208.869778255498;5209.7757027098605;5230.503102205284;3937.3700207967163;4614.238650622609;4618.67669263155;4624.722516649012;4638.721676699409;5743.339515955635;5845.9707701462075;5871.4128587141795;6068.484178844759;6081.57428979976;6426.164598602152;6431.839204019937;6466.288708242089;6536.215654121535;6543.574667646588;6050.364711705208;6051.394860513913;6055.303640081447;6060.393960603904;6091.51267898567;5698.088305871041;5706.372299047184;5723.998300297431;5732.971335143297;5736.110902810071;6337.081123237197;6496.512761542997;6576.572522786004;6580.934190658032;6676.632785196861;6506.544541882212;6527.216733991761;6536;6538.967305163444;6543.051257567721;6644.966775166286;6647.0029495576055;6692.83267918306;6717.731290748511;6723.193216814014;6306.558540902276;6359.3640635936035;6388.55280130405;6429.54993150475;6440.194975628046;6321.115043893767;6325.620462772248;6335.493160547053;6375.075614036032;6404.359564043559;6322.620642761449;6336.429721325103;6361.681915904279;6391.96804015977;6394.584352017104;6385.616862988236;6394.200724909387;6400.359964003563;6402.583832051088;6404.839879003062;6101.664408457577;6142.069075791408;6370.458511026614;6371;6396.168498095218;6216.968915155395;6330.9050364245495;6344.238691357066;6353.047042943595;6378.394052564999;6189.381061893775;6193.256809182927;6194.783182588588;6318.936810631835;6333.1766870306565 08:22:23 INFO - PROCESS | 1636 | |1;ext.html;784.2706283156664;812.0093485947147;875.1773333066917;888.4136469929848;891.0866361978975;38618.806905965466;39385;40994.59005409945;41064.17871642569;41123.58876411235;40612;40806.59193408065;40813.387799182965;40975.97560060993;41121;7138.286171382872;7296.635168241567;7346.742863999761;9006.14441628046;9176.816463670722;7223.097112860893;7275.818104547376;10654.254202205848;10873.347599144077;10928.726781830439;9166.358354915159;10852.945735271353;10863.67408977732;10875.673729788079;10883.619073332435;10789.97495237951;10793.730156746065;10804.18968577352;10828.296160749525;10829.83755243672;10434.634787782466;10488.528016239288;10500.159987201005;10500.789984200273;10512.842307365396;37621;37912.81043594792;37919.431208531896;37925.24149516994;37956.620433795586;3240.740740740735;3802.752821066622;3815.6947444204397;3857.2285542891254;3875.748076375027;26965.786539605568;26971.325716857038;27440.17679469619;28343.433131337257;28960.565591516144;2490.651308816767;3059.1128572713887;3068.4170007698467;3261.2988207535254;3267.1178781729036;354331.22834385664;355457.99999999744;356261.2186939075;356672.4332756691;359258.20370897977;51657.74171129196;51688.48311516837;51690;51981.480185197666;51981.74009130006;54198.45801542013;54229.72885135549;54308;54326.72836635871;54343.72828135834;14445.78331325031;14558.12651240929;14586.635334116732;14586.708265834835;14602.488912887999;12635.925946294485;12637.873621263854;12638.178518396458;12655.746885062246;12658.683532911751;10765.892341076646;12393.318367489876;12423.875761242452;12425.19236249641;12505.437255323532;5380.166074258496;5412.512873841373;5419.458054194549;5421.756020978986;5429.56563474921;961.1061712607202;968.1374972805255;968.7384406210284;970.7136394763562;981.9558119884622;27220.863895680395;27386.17841464759;27758.306042349104;27946.161615151577;28004.85997569999;1036.08824234673;1042.9009244121796;1046.7958748044211;1058.2856571813995;1082.1396989393452;13754.65613359674;13974.371153298125;14192.372433988518;14455.566333010027;14464.493742718954;409625;410396;411109;411116;411493.8850611455;53092.46907530953;53172.20241696377;53172.46827531675;53245.46754532405;53330;1301.5054279373778;1333.5265980576949;1459.1026518690928;1463.370750577262;1470.8602682745122;24000.399989999692;28696.282592935342;30080.548791767702;30606.540901886936;30751.308678022924;52132.73933630308;52443.95112097813;52522;52590;52601;461345;463975;464009;464296;464987;28172.873085076368;28772;29797.404051918405;29860.402791943605;34616.82691586526;36287.637123628425;37071.81464092662;37646.811765941;37664.43503347397;37777.433338500494;26346.077887273852;26770.46459070769;26792.19623411301;27694.584581230894;27719.72280277252;22355.66466502971;26906.192814215607;27517.761700723917;27525.862370688017;27662.03182888589;31045.0686479406;32016.35967280688;32035.35929281448;32073.67926320707;32179.51730724088;27104.457910842066;27649.447011060067;27839.582406264326;27935.44129117447;28691.856540718;21902.671459927795;22305.776942230368;22315.33054008382;22610;22762.317130486113 08:22:23 INFO - PROCESS | 1636 | |2;jquery.html;387.3821255098121;427.61563075729674;430.9935350969735;437.2282920645061;443.7359770936292;16134.83865161348;20444.89777551112;22221.444463888412;22282.442938926535;22535.211267605617;22561.66157507639;22874.542509149807;22923.19768808092;22929.52483178791;22947.08211671531;6589.93410065898;6618.271990081099;6825.249222585511;6915.585064896122;6935.375816176543;6591.373819487141;6770.932290677104;6885.380315771579;6905.585664860099;6916.2737912519215;6792.626405547693;6853.177618685765;6860.142482189726;6867.072945152416;6870.690818913136;6741.39327460526;6763.797086087426;6767.830804229884;6822.692978815965;6845.041694162821;6583.275839657682;6612.834679133012;6654.966725166391;6658.10115634388;6678.732850685967;27019.919203231846;27076.729232707617;27081.729182708117;27136.457270854473;27352.17943461699;2981.2695889506995;2983.209449495885;2991.5811786349927;3009.6388433388133;3017.6227971503563;7372.520786148883;7395.519291246105;7405.962970185168;14920.477783277533;16815.2433140509;2017.384697667211;2042.3872838148645;2043.8569300148995;2066.842568161819;2136.647453170225;134535;135767;137365.3131734335;138013;139810;15886.841131588768;22115.668264975717;22182.667259991114;22201;22499.775002249768;36627;37259.81370093187;37313.81343093267;37476.812615936746;37553.43669844954;7636.427267954926;8733.868991965126;9087.681931132378;9211.170994610384;9869.407835529892;7210.134783825974;7602.771916842504;7634.312911837961;7635.656395462218;7636.770896873103;7327.523710958771;8027.2775450209765;8816.603252853636;8821.029686734453;8863.468191908507;3410.795352278871;4024.8188831502653;4090.1819636072905;4114.814833332447;4127.318992366286;740.8851627997669;874.4534665833854;883.4257732473815;935.6912218967724;940.2571968145226;20335.288264910658;21612.351629450826;21629.675554866688;21697.34907952764;21737.782622173574;898.1153563739712;909.1363204955319;916.3081873185702;933.0622724162309;937.3906960475746;10797;11037.172212084126;11748.35384053868;12177.269363838199;12281.508739650313;757225;760038;766626;777313;782010;144611.5538844619;155247;155754.22122889312;163587;168119;1258.1130303136267;1266.6010206784833;1300.089937044073;1302.1405872124542;1303.6284658872366;19178.232870685017;20174.69737953961;20259.392218233475;20260.696089558962;20262.392128236177;51373.74313128411;66049.66975165093;66709;66866.33133668601;67118.30171887921;85776;86415;94645;96767.51616241873;98069;10850.728731781768;11308.038816700673;11479;11669.76660466769;11738.237014594024;12433.502659893504;12475;12696;12819.871801282241;12830.037747168813;10552.525136368728;10687.946560267148;10807.67576972692;10951.288166269167;11014.50434730423;10147.949260253652;10290.3825770454;10358.171346292429;10450.68647940563;10456.477176141314;10512.369257844553;11003.17476189273;11085.22403431752;11094.611688590861;11120.499577519193;10050.447225402673;10761.408122553335;10828.834233153244;10882.673519794418;11149.442527873736;8948.642054318016;10743.516541755484;10903.727406814893;11072.335659860435;11166.665000050012 08:22:23 INFO - PROCESS | 1636 | |3;mootools.html;1411.717656468706;1520.5742392130207;1550.3488534815376;1550.4883388481803;1573.0876091866714;10585.682429527116;10923.781524369508;11215.383153926541;11219;11257.324560526353;10945.01494865462;11138.665840024812;11139.944300278508;11143.777124457527;11146.944265278682;2332.4518738096576;2370.1822871109525;2370.2059809963685;2370.2533701883854;2404.1825779235055;2264.1396269417596;2318.7565305642916;2321.9883900580476;2325.348902307348;2354.2701762453607;2329.3827135809024;2383.7497062808416;2394.6647469354302;2395.9520809583796;2405.9879700601473;2318.038014224095;2329.2546385156757;2352.141468364052;2353.011735071266;2359.8230132740114;2352.0591763294647;2382.833201675883;2384.940376490584;2385.8449200801892;2386.809055275574;11066.723331916686;11305.26515776472;11327.433628318633;11333.150013749002;11340.886591134147;5611.382747897728;5775.451332123442;5788.855278618026;5792.275965504312;5799.971000145015;3462.342154990544;3512.8946131616094;3580.874669386559;3598.3702852000797;3619.511365965587;1092.7104317355906;1127.4250132432437;1182.4265231362783;1210.1649861595472;1230.44014973187;13660;13958.371873265727;14247.715045699028;15491.302891369915;15710.528684139494;4493.550644935481;4654.464736555339;4659.370984917061;4676.766161691902;4681.81272749093;11566.074714022858;11567.826482602768;11571.19001669875;11578.131640127023;11583.710407239718;4749.382580264543;4899.608031357483;5532.225488431623;5696.82909512707;5718.313802343662;5116.130257856174;5220.634555581073;5320.574354051667;5333;5336.199570064521;3903.1217975955183;3946.9013274668364;4003.139325045129;4025.376066709665;4026.4362989181536;1043.9008294212038;1058.1270451877233;1074.382230217628;1079.3739631013993;1080.8756992945755;909.3861643390685;931.5156118818177;933.7852293972288;939.9530023498935;975.8487434447673;6277.27175921111;6318.241810982619;6505.804825855232;6557.049227861933;6583.144191255202;990.4305024610877;1007;1008.7276435362558;1018.6587493189717;1021.8810402609131;3316.9054212109945;3321.5515905352468;4066.7153299269357;4095.569965153616;4121.166081093945;13289.66775830612;13902.791458128137;13923.094998825043;13926.721465570632;13946.721065578631;13815;13838.723225535434;13862.306884655727;13863.237521936391;13917.721645567235;9741;9877.851832222523;10082.59669613222;10378.844317335095;10383.325083869524;6302.590331628429;6360.904586431115;6387.457066149429;6408;6408.711607977745;10643.361398315816;11760.647180584596;12062.819057714318;12070.75858482843;12321.137520373846;10270.537825797708;10823.971722686332;11183.049440797624;11442.027427668409;12431.75136497247;8858;8953.462792232487;9198.67804626835;9279.39683920543;9280.443173409618;10019.899801002188;10070.546825393021;10199.694009179737;10199.898001020192;10218.897811021794;8606.956965215133;8651.91348086511;8705.695300664447;8719.607617657093;8886.955565222133;7258.637068146466;8433.915660843559;8506.61720222579;8524.531150786519;8678.609462574073;7646.8088297793;7780.299773020455;7792.337651299703;7800.843983120192;7820.84358312842;5692.089265717465;5778.422157784188;5780.479756821906;5919.7632094717455;6024.668643224665;5875.882482350415;5947.672877991579;6091.1472393864005;6154.476869466145;6174.475169610633 08:22:23 INFO - PROCESS | 1636 | |4;prototype.html;371;371.95350581177354;379.8689452139011;384.2507111133291;385.4449592586675;4202.432671589335;4362.825486980517;4422.358757980095;4895.902081958358;5019.774110165043;4633.397658304424;4774.328984855299;4876.463589005207;4901.485344038869;4955.430125535565;3184.6274525478434;4131.318332475138;4170.020045289354;4192.454980852485;4204.705670603059;3950.723449358546;4088.2641124597562;4117.9176416471655;4182.769947652878;4191.7484950903045;3654.5979942206336;3667.504886840269;3889.2999260133165;4201.390798334255;4212.915741685165;3635.745497815154;3954.940675889864;4186.748795072275;4219.957800422003;4386.135892245815;3283.453869820588;3698.14942563212;3861.3049651062797;3865.768053916774;3867.690584753213;4067.7355971861734;4282.678799090081;4304.481878101315;4825.782839772183;4837.298591704219;4065.6544193743566;4141.689373297015;4675.812967481296;4704.082703872747;4866.150862672197;4470.329450582439;4512.510871412426;5007.4241462231585;5272.789088436458;5368.436314187033;4249.723767955073;4267.722598031149;4276.059266961263;4284.943227250937;4316.266234740102;4737.620990320835;4887.315775791392;5456.543456543496;5638.689872057035;5661.348944871352;3767.434884767307;3821.885343439701;3931.3513270310077;4363.563643635674;4458.7324760514475;3835.8657446989223;3905.6135072049583;3945.546262179829;4486.327050942385;4506.481754598198;4412.852658308862;4480.372747815309;4506.3465797459185;4678.60231880294;4821.855344339676;4775.235962246094;4982.676126051795;5129.461406552333;5284.207368894697;5398.839249561363;1358.395513996282;1481.5925620454245;1497.7458924319048;1499.640086379283;1506.246876561719;972.4408465132577;1008.8284991551454;1046.874375074996;1068.3429690740295;1136.8858518651768;4119.876403707894;4121.732087414309;4957.553820156131;4966.95693904277;5017.588040132745;4625.676202665848;5428.56571474289;5585.218069470277;5586.441355864381;5597.916031259534;4503.594676479132;4552.93006143556;4954.578860796801;4976.979719157564;5005.449400565934;2318.6637937498967;2532.5947848344176;2876.280929767558;2884.5096333623333;2897.4350001749876;5045.672031317952;5070.490107396862;5616.66300022;5738.852229554106;5770.567207459458;4565.863024109282;4892.804287828447;4968.776405061781;5095.770690318945;5325.627206095536;4886.315915771795;4967.776450059684;5639.182318563785;5648.51987581052;5753.942460575341;4967.627427942846;5191.870203244949;5213.608979326642;5440.972795135999;5603.3836278008575;5268.81559145428;5364.865878353073;5420.566354691537;5428.782848686008;5487.64330318528;4793.579242482524;4939.456659767495;4993.675411098412;5622.662640241599;5689.008846903551;4210.663146948175;4379.781010949376;4693.095714977924;4778.617710583215;4843.903121937471;5116.948830511647;5282.724710679403;5444.972775136099;5472.343318801769;5525.530329921841;5007.499250074963;5102.183650615883;5375.489328513785;5415.241866138823;5434.918476222781;4421.381006659006;4480.611010586728;5273.762680679302;5350.956563470086;5400.297961265011;4724.456687480983;5015.523525265094;5164.715940623302;5246.580273578028;5338.97330513345;4634.675572709878;4831.492693267156;5448.564114870722;5449.174526533656;5450.700211488406;4695.859124226278;4761.547652972962;4960.429550601727;5316.680999140064;5348.3954813555 08:22:23 INFO - PROCESS | 1636 | |5;yui.html;724.8586525627504;750.5421692767411;754.5510421299329;824.5300178898028;840.8108175660475;15352.923235383822;16143.273552690127;16179.919100404497;16469.588260293498;16507.50477485676;14619.926900365483;14964.551063468114;15049.17229552377;15110;15779.526614201592;925.1303774452027;943.3915124744535;947.1286416496839;947.7014740356766;948.0993056596227;921.9907800922006;935.3873213045466;939.0140352629745;952.9714108576719;954.8281309364312;995.9153471954893;999.5801763259449;1000.5697550053474;1001.9549120289604;1002.1381611813835;991.4794732765283;994.4679596415883;994.7214779861614;995.348047029197;996.4419924842111;739.4343327354578;740.5038624121797;740.9925900740977;741.7478057460489;742.8922806193071;15337.539873803804;15773.60565985848;15816.920915395465;16176.83823161765;16442.177891105395;13902.513412030632;14167;14185.936054795828;14252.429902803875;14288.142711437347;11781.882181178164;11824.704382390422;11896.881031189663;11942.880571194264;12026.819597706042;3605.2428989911887;3648;3652.8721494747556;3659.7621154624862;3717.8698745543775;86220;87580;106704.4664776679;109121.90878091197;110488.89511104947;10603;10657.467126643636;11013.00882920541;11163.051140652973;11434.02810761078;20764.792352076587;22447.663285050738;22504.662430063563;22704.432389190402;22963.885180574325;7869.527828330319;7888.48724832884;7963.8407231855035;7985.361171106298;7988.320992715568;4271.209826182167;4311.633511151587;4353.978230108893;4442.822287108479;4550.49944506104;3386.695197432243;3448.689617934398;3478.2174010847357;3497;3501.7373696972827;602.6685323072293;612.8467883029243;622.4646803748773;628.5882746800853;628.8207860759661;666.2604508995012;679.9286074962157;697.1912581405545;705.108038331511;718.81310859177;10098.899011009942;10125.392476451289;10209.242364141126;10456.68629941103;10479.318844275098;702.8840241360118;703.8908969109796;707.4552594502204;710.9857802843915;714.3606472207346;4562.794674239667;4901.705897646081;4910.435299940553;4920.163572192736;4934.457209707001;92874.53562732278;96507.47327759794;97368;97744.53383199258;100847.49576252072;82753;83617.58191209006;84116.57941710252;85086.14913850906;85461;72569.6371518139;73844.5231095375;74137;74862;77521.2247877514;7642.656080476503;11061.778764424505;11268.323900565993;11320.207585468937;11360.715982100513;45652;45662.543374567154;46287;48927;51286.23070654018;63844;63997.680011599645;65985.68028639305;66028.6698566504;66576.6671166641;12256.632301030982;12564.434600442819;12663.113582049536;12694.619161425171;12714.237145771283;14255.928720356333;14303.928480357532;14384.928075359556;14609.707805844037;15111.395544178109;13294.468221271041;13460.798088028883;14605.707885842436;14659.70680586361;14932.701345972802;13757.10578812374;13785.724285514434;14354.3540540678;14485.20331381742;14852.331645076214;13197.406116724962;13706.040577159503;13722.519711810039;14013.159210447406;14045.719085618026;9653.276004299845;9761.560729767036;9966.700998970042;10005.199584033406;10072;9935.950320248352;10555.683329500127;11001.22991390595;11377.374244416636;11523.423828808694 08:22:23 INFO - PROCESS | 1636 | __end_tp_report 08:22:23 INFO - PROCESS | 1636 | __start_cc_report 08:22:23 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,2613 08:22:23 INFO - PROCESS | 1636 | __end_cc_report 08:22:23 INFO - PROCESS | 1636 | __startTimestamp1491492143840__endTimestamp 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | ------- Summary: start ------- 08:22:23 INFO - PROCESS | 1636 | Number of tests: 6 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5989.60 Median:6223.25 stddev:698.11 (11.2%) stddev-sans-first:655.99 08:22:23 INFO - PROCESS | 1636 | Values: 2870.8 3413.8 3425.3 3467.0 3477.1 5967.8 6007.8 6016.4 6024.5 6060.4 6007.5 6103.6 6124.7 6130.9 6135.2 5421.8 5430.4 5465.7 5474.3 5604.1 5361.8 5377.9 5420.1 5548.1 5563.4 5439.0 5469.1 5469.5 5469.8 5475.1 4669.9 5481.5 5491.5 5492.3 5495.3 5281.4 5422.6 5426.1 5433.8 5463.2 6099.1 6104.3 6113.3 6113.5 6114.0 6393.1 6415.8 6423.8 6433.0 6455.9 6418.5 6495.8 6547.7 6566.1 6576.1 6133.7 6201.1 6211.3 6212.5 6223.3 6352.0 6658.5 6669.0 6673.1 6680.0 5212.9 6338.1 6352.9 6369.2 6369.8 6508.9 6510.8 6521.8 6523.3 6530.3 6301.3 6440.4 6451.3 6455.9 6467.3 5288.9 6339.9 6373.4 6436.5 6457.2 5199.8 5204.6 5208.9 5209.8 5230.5 3937.4 4614.2 4618.7 4624.7 4638.7 5743.3 5846.0 5871.4 6068.5 6081.6 6426.2 6431.8 6466.3 6536.2 6543.6 6050.4 6051.4 6055.3 6060.4 6091.5 5698.1 5706.4 5724.0 5733.0 5736.1 6337.1 6496.5 6576.6 6580.9 6676.6 6506.5 6527.2 6536.0 6539.0 6543.1 6645.0 6647.0 6692.8 6717.7 6723.2 6306.6 6359.4 6388.6 6429.5 6440.2 6321.1 6325.6 6335.5 6375.1 6404.4 6322.6 6336.4 6361.7 6392.0 6394.6 6385.6 6394.2 6400.4 6402.6 6404.8 6101.7 6142.1 6370.5 6371.0 6396.2 6217.0 6330.9 6344.2 6353.0 6378.4 6189.4 6193.3 6194.8 6318.9 6333.2 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:54666.97 Median:32025.86 stddev:109443.28 (341.7%) stddev-sans-first:109675.52 08:22:23 INFO - PROCESS | 1636 | Values: 784.3 812.0 875.2 888.4 891.1 38618.8 39385.0 40994.6 41064.2 41123.6 40612.0 40806.6 40813.4 40976.0 41121.0 7138.3 7296.6 7346.7 9006.1 9176.8 7223.1 7275.8 10654.3 10873.3 10928.7 9166.4 10852.9 10863.7 10875.7 10883.6 10790.0 10793.7 10804.2 10828.3 10829.8 10434.6 10488.5 10500.2 10500.8 10512.8 37621.0 37912.8 37919.4 37925.2 37956.6 3240.7 3802.8 3815.7 3857.2 3875.7 26965.8 26971.3 27440.2 28343.4 28960.6 2490.7 3059.1 3068.4 3261.3 3267.1 354331.2 355458.0 356261.2 356672.4 359258.2 51657.7 51688.5 51690.0 51981.5 51981.7 54198.5 54229.7 54308.0 54326.7 54343.7 14445.8 14558.1 14586.6 14586.7 14602.5 12635.9 12637.9 12638.2 12655.7 12658.7 10765.9 12393.3 12423.9 12425.2 12505.4 5380.2 5412.5 5419.5 5421.8 5429.6 961.1 968.1 968.7 970.7 982.0 27220.9 27386.2 27758.3 27946.2 28004.9 1036.1 1042.9 1046.8 1058.3 1082.1 13754.7 13974.4 14192.4 14455.6 14464.5 409625.0 410396.0 411109.0 411116.0 411493.9 53092.5 53172.2 53172.5 53245.5 53330.0 1301.5 1333.5 1459.1 1463.4 1470.9 24000.4 28696.3 30080.5 30606.5 30751.3 52132.7 52444.0 52522.0 52590.0 52601.0 461345.0 463975.0 464009.0 464296.0 464987.0 28172.9 28772.0 29797.4 29860.4 34616.8 36287.6 37071.8 37646.8 37664.4 37777.4 26346.1 26770.5 26792.2 27694.6 27719.7 22355.7 26906.2 27517.8 27525.9 27662.0 31045.1 32016.4 32035.4 32073.7 32179.5 27104.5 27649.4 27839.6 27935.4 28691.9 21902.7 22305.8 22315.3 22610.0 22762.3 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:43349.53 Median:15166.72 stddev:127889.77 (843.2%) stddev-sans-first:128207.85 08:22:23 INFO - PROCESS | 1636 | Values: 387.4 427.6 431.0 437.2 443.7 16134.8 20444.9 22221.4 22282.4 22535.2 22561.7 22874.5 22923.2 22929.5 22947.1 6589.9 6618.3 6825.2 6915.6 6935.4 6591.4 6770.9 6885.4 6905.6 6916.3 6792.6 6853.2 6860.1 6867.1 6870.7 6741.4 6763.8 6767.8 6822.7 6845.0 6583.3 6612.8 6655.0 6658.1 6678.7 27019.9 27076.7 27081.7 27136.5 27352.2 2981.3 2983.2 2991.6 3009.6 3017.6 7372.5 7395.5 7406.0 14920.5 16815.2 2017.4 2042.4 2043.9 2066.8 2136.6 134535.0 135767.0 137365.3 138013.0 139810.0 15886.8 22115.7 22182.7 22201.0 22499.8 36627.0 37259.8 37313.8 37476.8 37553.4 7636.4 8733.9 9087.7 9211.2 9869.4 7210.1 7602.8 7634.3 7635.7 7636.8 7327.5 8027.3 8816.6 8821.0 8863.5 3410.8 4024.8 4090.2 4114.8 4127.3 740.9 874.5 883.4 935.7 940.3 20335.3 21612.4 21629.7 21697.3 21737.8 898.1 909.1 916.3 933.1 937.4 10797.0 11037.2 11748.4 12177.3 12281.5 757225.0 760038.0 766626.0 777313.0 782010.0 144611.6 155247.0 155754.2 163587.0 168119.0 1258.1 1266.6 1300.1 1302.1 1303.6 19178.2 20174.7 20259.4 20260.7 20262.4 51373.7 66049.7 66709.0 66866.3 67118.3 85776.0 86415.0 94645.0 96767.5 98069.0 10850.7 11308.0 11479.0 11669.8 11738.2 12433.5 12475.0 12696.0 12819.9 12830.0 10552.5 10687.9 10807.7 10951.3 11014.5 10147.9 10290.4 10358.2 10450.7 10456.5 10512.4 11003.2 11085.2 11094.6 11120.5 10050.4 10761.4 10828.8 10882.7 11149.4 8948.6 10743.5 10903.7 11072.3 11166.7 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:6635.29 Median:2385.39 stddev:4154.39 (174.2%) stddev-sans-first:4147.50 08:22:23 INFO - PROCESS | 1636 | Values: 1411.7 1520.6 1550.3 1550.5 1573.1 10585.7 10923.8 11215.4 11219.0 11257.3 10945.0 11138.7 11139.9 11143.8 11146.9 2332.5 2370.2 2370.2 2370.3 2404.2 2264.1 2318.8 2322.0 2325.3 2354.3 2329.4 2383.7 2394.7 2396.0 2406.0 2318.0 2329.3 2352.1 2353.0 2359.8 2352.1 2382.8 2384.9 2385.8 2386.8 11066.7 11305.3 11327.4 11333.2 11340.9 5611.4 5775.5 5788.9 5792.3 5800.0 3462.3 3512.9 3580.9 3598.4 3619.5 1092.7 1127.4 1182.4 1210.2 1230.4 13660.0 13958.4 14247.7 15491.3 15710.5 4493.6 4654.5 4659.4 4676.8 4681.8 11566.1 11567.8 11571.2 11578.1 11583.7 4749.4 4899.6 5532.2 5696.8 5718.3 5116.1 5220.6 5320.6 5333.0 5336.2 3903.1 3946.9 4003.1 4025.4 4026.4 1043.9 1058.1 1074.4 1079.4 1080.9 909.4 931.5 933.8 940.0 975.8 6277.3 6318.2 6505.8 6557.0 6583.1 990.4 1007.0 1008.7 1018.7 1021.9 3316.9 3321.6 4066.7 4095.6 4121.2 13289.7 13902.8 13923.1 13926.7 13946.7 13815.0 13838.7 13862.3 13863.2 13917.7 9741.0 9877.9 10082.6 10378.8 10383.3 6302.6 6360.9 6387.5 6408.0 6408.7 10643.4 11760.6 12062.8 12070.8 12321.1 10270.5 10824.0 11183.0 11442.0 12431.8 8858.0 8953.5 9198.7 9279.4 9280.4 10019.9 10070.5 10199.7 10199.9 10218.9 8607.0 8651.9 8705.7 8719.6 8887.0 7258.6 8433.9 8506.6 8524.5 8678.6 7646.8 7780.3 7792.3 7800.8 7820.8 5692.1 5778.4 5780.5 5919.8 6024.7 5875.9 5947.7 6091.1 6154.5 6174.5 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4391.72 Median:4749.58 stddev:1229.31 (25.9%) stddev-sans-first:1195.14 08:22:23 INFO - PROCESS | 1636 | Values: 371.0 372.0 379.9 384.3 385.4 4202.4 4362.8 4422.4 4895.9 5019.8 4633.4 4774.3 4876.5 4901.5 4955.4 3184.6 4131.3 4170.0 4192.5 4204.7 3950.7 4088.3 4117.9 4182.8 4191.7 3654.6 3667.5 3889.3 4201.4 4212.9 3635.7 3954.9 4186.7 4220.0 4386.1 3283.5 3698.1 3861.3 3865.8 3867.7 4067.7 4282.7 4304.5 4825.8 4837.3 4065.7 4141.7 4675.8 4704.1 4866.2 4470.3 4512.5 5007.4 5272.8 5368.4 4249.7 4267.7 4276.1 4284.9 4316.3 4737.6 4887.3 5456.5 5638.7 5661.3 3767.4 3821.9 3931.4 4363.6 4458.7 3835.9 3905.6 3945.5 4486.3 4506.5 4412.9 4480.4 4506.3 4678.6 4821.9 4775.2 4982.7 5129.5 5284.2 5398.8 1358.4 1481.6 1497.7 1499.6 1506.2 972.4 1008.8 1046.9 1068.3 1136.9 4119.9 4121.7 4957.6 4967.0 5017.6 4625.7 5428.6 5585.2 5586.4 5597.9 4503.6 4552.9 4954.6 4977.0 5005.4 2318.7 2532.6 2876.3 2884.5 2897.4 5045.7 5070.5 5616.7 5738.9 5770.6 4565.9 4892.8 4968.8 5095.8 5325.6 4886.3 4967.8 5639.2 5648.5 5753.9 4967.6 5191.9 5213.6 5441.0 5603.4 5268.8 5364.9 5420.6 5428.8 5487.6 4793.6 4939.5 4993.7 5622.7 5689.0 4210.7 4379.8 4693.1 4778.6 4843.9 5116.9 5282.7 5445.0 5472.3 5525.5 5007.5 5102.2 5375.5 5415.2 5434.9 4421.4 4480.6 5273.8 5351.0 5400.3 4724.5 5015.5 5164.7 5246.6 5339.0 4634.7 4831.5 5448.6 5449.2 5450.7 4695.9 4761.5 4960.4 5316.7 5348.4 08:22:23 INFO - PROCESS | 1636 | 08:22:23 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19937.62 Median:3688.82 stddev:27798.77 (753.6%) stddev-sans-first:27839.32 08:22:23 INFO - PROCESS | 1636 | Values: 724.9 750.5 754.6 824.5 840.8 15352.9 16143.3 16179.9 16469.6 16507.5 14619.9 14964.6 15049.2 15110.0 15779.5 925.1 943.4 947.1 947.7 948.1 922.0 935.4 939.0 953.0 954.8 995.9 999.6 1000.6 1002.0 1002.1 991.5 994.5 994.7 995.3 996.4 739.4 740.5 741.0 741.7 742.9 15337.5 15773.6 15816.9 16176.8 16442.2 13902.5 14167.0 14185.9 14252.4 14288.1 11781.9 11824.7 11896.9 11942.9 12026.8 3605.2 3648.0 3652.9 3659.8 3717.9 86220.0 87580.0 106704.5 109121.9 110488.9 10603.0 10657.5 11013.0 11163.1 11434.0 20764.8 22447.7 22504.7 22704.4 22963.9 7869.5 7888.5 7963.8 7985.4 7988.3 4271.2 4311.6 4354.0 4442.8 4550.5 3386.7 3448.7 3478.2 3497.0 3501.7 602.7 612.8 622.5 628.6 628.8 666.3 679.9 697.2 705.1 718.8 10098.9 10125.4 10209.2 10456.7 10479.3 702.9 703.9 707.5 711.0 714.4 4562.8 4901.7 4910.4 4920.2 4934.5 92874.5 96507.5 97368.0 97744.5 100847.5 82753.0 83617.6 84116.6 85086.1 85461.0 72569.6 73844.5 74137.0 74862.0 77521.2 7642.7 11061.8 11268.3 11320.2 11360.7 45652.0 45662.5 46287.0 48927.0 51286.2 63844.0 63997.7 65985.7 66028.7 66576.7 12256.6 12564.4 12663.1 12694.6 12714.2 14255.9 14303.9 14384.9 14609.7 15111.4 13294.5 13460.8 14605.7 14659.7 14932.7 13757.1 13785.7 14354.4 14485.2 14852.3 13197.4 13706.0 13722.5 14013.2 14045.7 9653.3 9761.6 9966.7 10005.2 10072.0 9936.0 10555.7 11001.2 11377.4 11523.4 08:22:23 INFO - PROCESS | 1636 | -------- Summary: end -------- 08:22:23 INFO - PROCESS | 1636 | 08:22:24 INFO - TEST-INFO | 1636: exit 0 08:22:24 INFO - TEST-OK | dromaeo_css | took 1121383ms 08:22:24 INFO - TEST-START | kraken 08:22:24 INFO - operating with platform_type : mac_ 08:22:24 INFO - Initialising browser for kraken test... 08:22:24 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmprIGPou/profile http://localhost:49245/getInfo.html) 08:22:26 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 08:22:26 INFO - PROCESS | 1646 | colorDepth:24 08:22:26 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 08:22:26 INFO - PROCESS | 1646 | __metrics 08:22:26 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:22:26 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:22:27 INFO - TEST-INFO | 1646: exit 0 08:22:27 INFO - Browser initialized. 08:22:27 INFO - Running cycle 1/1 for kraken test... 08:22:27 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmprIGPou/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:40 INFO - PROCESS | 1647 | RSS: Main: 210931712 08:22:40 INFO - PROCESS | 1647 | 08:22:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 08:22:43 INFO - PROCESS | 1647 | RSS: Main: 264261632 08:22:43 INFO - PROCESS | 1647 | 08:22:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 08:22:46 INFO - PROCESS | 1647 | RSS: Main: 277671936 08:22:46 INFO - PROCESS | 1647 | 08:22:50 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 08:22:50 INFO - PROCESS | 1647 | RSS: Main: 375054336 08:22:50 INFO - PROCESS | 1647 | 08:22:52 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 08:22:53 INFO - PROCESS | 1647 | RSS: Main: 278630400 08:22:53 INFO - PROCESS | 1647 | 08:22:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:55 INFO - PROCESS | 1647 | RSS: Main: 280375296 08:22:55 INFO - PROCESS | 1647 | 08:22:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:59 INFO - PROCESS | 1647 | RSS: Main: 320843776 08:22:59 INFO - PROCESS | 1647 | 08:23:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 08:23:03 INFO - PROCESS | 1647 | RSS: Main: 322322432 08:23:03 INFO - PROCESS | 1647 | 08:23:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 08:23:06 INFO - PROCESS | 1647 | RSS: Main: 318623744 08:23:06 INFO - PROCESS | 1647 | 08:23:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:23:08 INFO - PROCESS | 1647 | RSS: Main: 296079360 08:23:08 INFO - PROCESS | 1647 | 08:23:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:23:11 INFO - PROCESS | 1647 | RSS: Main: 298508288 08:23:11 INFO - PROCESS | 1647 | 08:23:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:23:14 INFO - PROCESS | 1647 | RSS: Main: 308137984 08:23:14 INFO - PROCESS | 1647 | 08:23:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:23:17 INFO - PROCESS | 1647 | RSS: Main: 320327680 08:23:17 INFO - PROCESS | 1647 | 08:23:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:23:21 INFO - PROCESS | 1647 | RSS: Main: 313143296 08:23:21 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 08:23:23 INFO - PROCESS | 1647 | RSS: Main: 357908480 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | __start_tp_report 08:23:23 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 08:23:23 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 08:23:23 INFO - PROCESS | 1647 | |i|pagename|runs| 08:23:23 INFO - PROCESS | 1647 | |0;ai-astar;107;116;116;117;116;117;116;116;117;115 08:23:23 INFO - PROCESS | 1647 | |1;audio-beat-detection;143;111;108;145;110;109;108;108;126;110 08:23:23 INFO - PROCESS | 1647 | |2;audio-dft;185;184;182;187;185;184;181;180;184;184 08:23:23 INFO - PROCESS | 1647 | |3;audio-fft;83;60;62;92;64;61;61;61;77;60 08:23:23 INFO - PROCESS | 1647 | |4;audio-oscillator;84;68;86;69;70;69;81;70;69;84 08:23:23 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;87;87;87;87;93;136;87;86;92;136 08:23:23 INFO - PROCESS | 1647 | |6;imaging-darkroom;94;95;95;95;95;94;95;95;93;94 08:23:23 INFO - PROCESS | 1647 | |7;imaging-desaturate;85;83;83;83;85;84;83;86;83;84 08:23:23 INFO - PROCESS | 1647 | |8;json-parse-financial;61;61;60;59;64;63;63;63;63;62 08:23:23 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;60;54;59;64;58;57;59;57;59;59 08:23:23 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;87;83;83;86;85;83;85;90;84;96 08:23:23 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;135;137;136;134;137;135;137;141;179;139 08:23:23 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;164;164;166;166;165;166;167;166;164;163 08:23:23 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;64;66;64;62;67;64;67;64;64;64 08:23:23 INFO - PROCESS | 1647 | __end_tp_report 08:23:23 INFO - PROCESS | 1647 | __start_cc_report 08:23:23 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2168 08:23:23 INFO - PROCESS | 1647 | __end_cc_report 08:23:23 INFO - PROCESS | 1647 | __startTimestamp1491492203250__endTimestamp 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | ------- Summary: start ------- 08:23:23 INFO - PROCESS | 1647 | Number of tests: 14 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.00 stddev:2.98 (2.6%) stddev-sans-first:0.67 08:23:23 INFO - PROCESS | 1647 | Values: 107.0 116.0 116.0 117.0 116.0 117.0 116.0 116.0 117.0 115.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:117.80 Median:110.50 stddev:14.82 (13.4%) stddev-sans-first:12.60 08:23:23 INFO - PROCESS | 1647 | Values: 143.0 111.0 108.0 145.0 110.0 109.0 108.0 108.0 126.0 110.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:2.07 (1.1%) stddev-sans-first:2.13 08:23:23 INFO - PROCESS | 1647 | Values: 185.0 184.0 182.0 187.0 185.0 184.0 181.0 180.0 184.0 184.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:68.10 Median:63.00 stddev:11.59 (18.4%) stddev-sans-first:10.97 08:23:23 INFO - PROCESS | 1647 | Values: 83.0 60.0 62.0 92.0 64.0 61.0 61.0 61.0 77.0 60.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.50 stddev:7.64 (10.1%) stddev-sans-first:7.38 08:23:23 INFO - PROCESS | 1647 | Values: 84.0 68.0 86.0 69.0 70.0 69.0 81.0 70.0 69.0 84.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:87.00 stddev:20.27 (23.3%) stddev-sans-first:21.12 08:23:23 INFO - PROCESS | 1647 | Values: 87.0 87.0 87.0 87.0 93.0 136.0 87.0 86.0 92.0 136.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 08:23:23 INFO - PROCESS | 1647 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 93.0 94.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:1.10 (1.3%) stddev-sans-first:1.09 08:23:23 INFO - PROCESS | 1647 | Values: 85.0 83.0 83.0 83.0 85.0 84.0 83.0 86.0 83.0 84.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:63.00 stddev:1.60 (2.5%) stddev-sans-first:1.66 08:23:23 INFO - PROCESS | 1647 | Values: 61.0 61.0 60.0 59.0 64.0 63.0 63.0 63.0 63.0 62.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:2.55 (4.3%) stddev-sans-first:2.65 08:23:23 INFO - PROCESS | 1647 | Values: 60.0 54.0 59.0 64.0 58.0 57.0 59.0 57.0 59.0 59.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.50 stddev:4.08 (4.8%) stddev-sans-first:4.31 08:23:23 INFO - PROCESS | 1647 | Values: 87.0 83.0 83.0 86.0 85.0 83.0 85.0 90.0 84.0 96.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:137.00 stddev:13.51 (9.9%) stddev-sans-first:14.15 08:23:23 INFO - PROCESS | 1647 | Values: 135.0 137.0 136.0 134.0 137.0 135.0 137.0 141.0 179.0 139.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:1.29 (0.8%) stddev-sans-first:1.30 08:23:23 INFO - PROCESS | 1647 | Values: 164.0 164.0 166.0 166.0 165.0 166.0 167.0 166.0 164.0 163.0 08:23:23 INFO - PROCESS | 1647 | 08:23:23 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.00 stddev:1.58 (2.5%) stddev-sans-first:1.66 08:23:23 INFO - PROCESS | 1647 | Values: 64.0 66.0 64.0 62.0 67.0 64.0 67.0 64.0 64.0 64.0 08:23:23 INFO - PROCESS | 1647 | -------- Summary: end -------- 08:23:23 INFO - PROCESS | 1647 | 08:23:24 INFO - TEST-INFO | 1647: exit 0 08:23:24 INFO - TEST-OK | kraken | took 59621ms 08:23:24 INFO - SUITE-END | took 1181s 08:23:24 INFO - Completed test suite (00:19:41) 08:23:24 INFO - javascript benchmark 08:23:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7593.894676063029, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2870.8277503349796, 3413.8293085345736, 3425.263568332808, 3467.0, 3477.1307173206683, 5967.82096537104, 6007.7596896124105, 6016.368281330463, 6024.54815888809, 6060.393960603938, 6007.519398448125, 6103.633781973084, 6124.693765311739, 6130.9080363794465, 6135.202423684926, 5421.83734487965, 5430.402655707869, 5465.672059676413, 5474.315710536183, 5604.103343465046, 5361.812336568221, 5377.946220537784, 5420.1327787554, 5548.056830338832, 5563.443655634424, 5438.972805135969, 5469.124940009599, 5469.480399362054, 5469.781208751665, 5475.0966090595, 4669.8832529186875, 5481.451854814526, 5491.478309560565, 5492.340919089695, 5495.2856128703415, 5281.383990897945, 5422.566194704415, 5426.131818908995, 5433.782648694048, 5463.180522921593, 6099.054646529795, 6104.2980057293535, 6113.266408031065, 6113.510919126459, 6113.991191453405, 6393.073004414334, 6415.775447859349, 6423.807285781434, 6432.970724684075, 6455.870882582322, 6418.454431373339, 6495.805125846185, 6547.738090476375, 6566.146400967891, 6576.112224849632, 6133.693315334215, 6201.069839524062, 6211.25464944205, 6212.502999760009, 6223.25320961483, 6351.96824015877, 6658.500612454037, 6669.0, 6673.0991316172185, 6679.998000299994, 5212.947870521322, 6338.144350512714, 6352.872942541123, 6369.20384951881, 6369.840753981095, 6508.9023664645065, 6510.7721229756735, 6521.804345869631, 6523.3150519194805, 6530.314316996647, 6301.275353334334, 6440.420362167334, 6451.258105317855, 6455.870882582322, 6467.288598254188, 5288.867778305573, 6339.904901426482, 6373.3945275198785, 6436.549441539048, 6457.160569125984, 5199.792008319625, 5204.583633309327, 5208.869778255498, 5209.7757027098605, 5230.503102205284, 3937.3700207967163, 4614.238650622609, 4618.67669263155, 4624.722516649012, 4638.721676699409, 5743.339515955635, 5845.9707701462075, 5871.4128587141795, 6068.484178844759, 6081.57428979976, 6426.164598602152, 6431.839204019937, 6466.288708242089, 6536.215654121535, 6543.574667646588, 6050.364711705208, 6051.394860513913, 6055.303640081447, 6060.393960603904, 6091.51267898567, 5698.088305871041, 5706.372299047184, 5723.998300297431, 5732.971335143297, 5736.110902810071, 6337.081123237197, 6496.512761542997, 6576.572522786004, 6580.934190658032, 6676.632785196861, 6506.544541882212, 6527.216733991761, 6536.0, 6538.967305163444, 6543.051257567721, 6644.966775166286, 6647.0029495576055, 6692.83267918306, 6717.731290748511, 6723.193216814014, 6306.558540902276, 6359.3640635936035, 6388.55280130405, 6429.54993150475, 6440.194975628046, 6321.115043893767, 6325.620462772248, 6335.493160547053, 6375.075614036032, 6404.359564043559, 6322.620642761449, 6336.429721325103, 6361.681915904279, 6391.96804015977, 6394.584352017104, 6385.616862988236, 6394.200724909387, 6400.359964003563, 6402.583832051088, 6404.839879003062, 6101.664408457577, 6142.069075791408, 6370.458511026614, 6371.0, 6396.168498095218, 6216.968915155395, 6330.9050364245495, 6344.238691357066, 6353.047042943595, 6378.394052564999, 6189.381061893775, 6193.256809182927, 6194.783182588588, 6318.936810631835, 6333.1766870306565], "value": 5942.071118247552, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.2706283156664, 812.0093485947147, 875.1773333066917, 888.4136469929848, 891.0866361978975, 38618.806905965466, 39385.0, 40994.59005409945, 41064.17871642569, 41123.58876411235, 40612.0, 40806.59193408065, 40813.387799182965, 40975.97560060993, 41121.0, 7138.286171382872, 7296.635168241567, 7346.742863999761, 9006.14441628046, 9176.816463670722, 7223.097112860893, 7275.818104547376, 10654.254202205848, 10873.347599144077, 10928.726781830439, 9166.358354915159, 10852.945735271353, 10863.67408977732, 10875.673729788079, 10883.619073332435, 10789.97495237951, 10793.730156746065, 10804.18968577352, 10828.296160749525, 10829.83755243672, 10434.634787782466, 10488.528016239288, 10500.159987201005, 10500.789984200273, 10512.842307365396, 37621.0, 37912.81043594792, 37919.431208531896, 37925.24149516994, 37956.620433795586, 3240.740740740735, 3802.752821066622, 3815.6947444204397, 3857.2285542891254, 3875.748076375027, 26965.786539605568, 26971.325716857038, 27440.17679469619, 28343.433131337257, 28960.565591516144, 2490.651308816767, 3059.1128572713887, 3068.4170007698467, 3261.2988207535254, 3267.1178781729036, 354331.22834385664, 355457.99999999744, 356261.2186939075, 356672.4332756691, 359258.20370897977, 51657.74171129196, 51688.48311516837, 51690.0, 51981.480185197666, 51981.74009130006, 54198.45801542013, 54229.72885135549, 54308.0, 54326.72836635871, 54343.72828135834, 14445.78331325031, 14558.12651240929, 14586.635334116732, 14586.708265834835, 14602.488912887999, 12635.925946294485, 12637.873621263854, 12638.178518396458, 12655.746885062246, 12658.683532911751, 10765.892341076646, 12393.318367489876, 12423.875761242452, 12425.19236249641, 12505.437255323532, 5380.166074258496, 5412.512873841373, 5419.458054194549, 5421.756020978986, 5429.56563474921, 961.1061712607202, 968.1374972805255, 968.7384406210284, 970.7136394763562, 981.9558119884622, 27220.863895680395, 27386.17841464759, 27758.306042349104, 27946.161615151577, 28004.85997569999, 1036.08824234673, 1042.9009244121796, 1046.7958748044211, 1058.2856571813995, 1082.1396989393452, 13754.65613359674, 13974.371153298125, 14192.372433988518, 14455.566333010027, 14464.493742718954, 409625.0, 410396.0, 411109.0, 411116.0, 411493.8850611455, 53092.46907530953, 53172.20241696377, 53172.46827531675, 53245.46754532405, 53330.0, 1301.5054279373778, 1333.5265980576949, 1459.1026518690928, 1463.370750577262, 1470.8602682745122, 24000.399989999692, 28696.282592935342, 30080.548791767702, 30606.540901886936, 30751.308678022924, 52132.73933630308, 52443.95112097813, 52522.0, 52590.0, 52601.0, 461345.0, 463975.0, 464009.0, 464296.0, 464987.0, 28172.873085076368, 28772.0, 29797.404051918405, 29860.402791943605, 34616.82691586526, 36287.637123628425, 37071.81464092662, 37646.811765941, 37664.43503347397, 37777.433338500494, 26346.077887273852, 26770.46459070769, 26792.19623411301, 27694.584581230894, 27719.72280277252, 22355.66466502971, 26906.192814215607, 27517.761700723917, 27525.862370688017, 27662.03182888589, 31045.0686479406, 32016.35967280688, 32035.35929281448, 32073.67926320707, 32179.51730724088, 27104.457910842066, 27649.447011060067, 27839.582406264326, 27935.44129117447, 28691.856540718, 21902.671459927795, 22305.776942230368, 22315.33054008382, 22610.0, 22762.317130486113], "value": 18237.33283487167, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.3821255098121, 427.61563075729674, 430.9935350969735, 437.2282920645061, 443.7359770936292, 16134.83865161348, 20444.89777551112, 22221.444463888412, 22282.442938926535, 22535.211267605617, 22561.66157507639, 22874.542509149807, 22923.19768808092, 22929.52483178791, 22947.08211671531, 6589.93410065898, 6618.271990081099, 6825.249222585511, 6915.585064896122, 6935.375816176543, 6591.373819487141, 6770.932290677104, 6885.380315771579, 6905.585664860099, 6916.2737912519215, 6792.626405547693, 6853.177618685765, 6860.142482189726, 6867.072945152416, 6870.690818913136, 6741.39327460526, 6763.797086087426, 6767.830804229884, 6822.692978815965, 6845.041694162821, 6583.275839657682, 6612.834679133012, 6654.966725166391, 6658.10115634388, 6678.732850685967, 27019.919203231846, 27076.729232707617, 27081.729182708117, 27136.457270854473, 27352.17943461699, 2981.2695889506995, 2983.209449495885, 2991.5811786349927, 3009.6388433388133, 3017.6227971503563, 7372.520786148883, 7395.519291246105, 7405.962970185168, 14920.477783277533, 16815.2433140509, 2017.384697667211, 2042.3872838148645, 2043.8569300148995, 2066.842568161819, 2136.647453170225, 134535.0, 135767.0, 137365.3131734335, 138013.0, 139810.0, 15886.841131588768, 22115.668264975717, 22182.667259991114, 22201.0, 22499.775002249768, 36627.0, 37259.81370093187, 37313.81343093267, 37476.812615936746, 37553.43669844954, 7636.427267954926, 8733.868991965126, 9087.681931132378, 9211.170994610384, 9869.407835529892, 7210.134783825974, 7602.771916842504, 7634.312911837961, 7635.656395462218, 7636.770896873103, 7327.523710958771, 8027.2775450209765, 8816.603252853636, 8821.029686734453, 8863.468191908507, 3410.795352278871, 4024.8188831502653, 4090.1819636072905, 4114.814833332447, 4127.318992366286, 740.8851627997669, 874.4534665833854, 883.4257732473815, 935.6912218967724, 940.2571968145226, 20335.288264910658, 21612.351629450826, 21629.675554866688, 21697.34907952764, 21737.782622173574, 898.1153563739712, 909.1363204955319, 916.3081873185702, 933.0622724162309, 937.3906960475746, 10797.0, 11037.172212084126, 11748.35384053868, 12177.269363838199, 12281.508739650313, 757225.0, 760038.0, 766626.0, 777313.0, 782010.0, 144611.5538844619, 155247.0, 155754.22122889312, 163587.0, 168119.0, 1258.1130303136267, 1266.6010206784833, 1300.089937044073, 1302.1405872124542, 1303.6284658872366, 19178.232870685017, 20174.69737953961, 20259.392218233475, 20260.696089558962, 20262.392128236177, 51373.74313128411, 66049.66975165093, 66709.0, 66866.33133668601, 67118.30171887921, 85776.0, 86415.0, 94645.0, 96767.51616241873, 98069.0, 10850.728731781768, 11308.038816700673, 11479.0, 11669.76660466769, 11738.237014594024, 12433.502659893504, 12475.0, 12696.0, 12819.871801282241, 12830.037747168813, 10552.525136368728, 10687.946560267148, 10807.67576972692, 10951.288166269167, 11014.50434730423, 10147.949260253652, 10290.3825770454, 10358.171346292429, 10450.68647940563, 10456.477176141314, 10512.369257844553, 11003.17476189273, 11085.22403431752, 11094.611688590861, 11120.499577519193, 10050.447225402673, 10761.408122553335, 10828.834233153244, 10882.673519794418, 11149.442527873736, 8948.642054318016, 10743.516541755484, 10903.727406814893, 11072.335659860435, 11166.665000050012], "value": 11335.336697873523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.717656468706, 1520.5742392130207, 1550.3488534815376, 1550.4883388481803, 1573.0876091866714, 10585.682429527116, 10923.781524369508, 11215.383153926541, 11219.0, 11257.324560526353, 10945.01494865462, 11138.665840024812, 11139.944300278508, 11143.777124457527, 11146.944265278682, 2332.4518738096576, 2370.1822871109525, 2370.2059809963685, 2370.2533701883854, 2404.1825779235055, 2264.1396269417596, 2318.7565305642916, 2321.9883900580476, 2325.348902307348, 2354.2701762453607, 2329.3827135809024, 2383.7497062808416, 2394.6647469354302, 2395.9520809583796, 2405.9879700601473, 2318.038014224095, 2329.2546385156757, 2352.141468364052, 2353.011735071266, 2359.8230132740114, 2352.0591763294647, 2382.833201675883, 2384.940376490584, 2385.8449200801892, 2386.809055275574, 11066.723331916686, 11305.26515776472, 11327.433628318633, 11333.150013749002, 11340.886591134147, 5611.382747897728, 5775.451332123442, 5788.855278618026, 5792.275965504312, 5799.971000145015, 3462.342154990544, 3512.8946131616094, 3580.874669386559, 3598.3702852000797, 3619.511365965587, 1092.7104317355906, 1127.4250132432437, 1182.4265231362783, 1210.1649861595472, 1230.44014973187, 13660.0, 13958.371873265727, 14247.715045699028, 15491.302891369915, 15710.528684139494, 4493.550644935481, 4654.464736555339, 4659.370984917061, 4676.766161691902, 4681.81272749093, 11566.074714022858, 11567.826482602768, 11571.19001669875, 11578.131640127023, 11583.710407239718, 4749.382580264543, 4899.608031357483, 5532.225488431623, 5696.82909512707, 5718.313802343662, 5116.130257856174, 5220.634555581073, 5320.574354051667, 5333.0, 5336.199570064521, 3903.1217975955183, 3946.9013274668364, 4003.139325045129, 4025.376066709665, 4026.4362989181536, 1043.9008294212038, 1058.1270451877233, 1074.382230217628, 1079.3739631013993, 1080.8756992945755, 909.3861643390685, 931.5156118818177, 933.7852293972288, 939.9530023498935, 975.8487434447673, 6277.27175921111, 6318.241810982619, 6505.804825855232, 6557.049227861933, 6583.144191255202, 990.4305024610877, 1007.0, 1008.7276435362558, 1018.6587493189717, 1021.8810402609131, 3316.9054212109945, 3321.5515905352468, 4066.7153299269357, 4095.569965153616, 4121.166081093945, 13289.66775830612, 13902.791458128137, 13923.094998825043, 13926.721465570632, 13946.721065578631, 13815.0, 13838.723225535434, 13862.306884655727, 13863.237521936391, 13917.721645567235, 9741.0, 9877.851832222523, 10082.59669613222, 10378.844317335095, 10383.325083869524, 6302.590331628429, 6360.904586431115, 6387.457066149429, 6408.0, 6408.711607977745, 10643.361398315816, 11760.647180584596, 12062.819057714318, 12070.75858482843, 12321.137520373846, 10270.537825797708, 10823.971722686332, 11183.049440797624, 11442.027427668409, 12431.75136497247, 8858.0, 8953.462792232487, 9198.67804626835, 9279.39683920543, 9280.443173409618, 10019.899801002188, 10070.546825393021, 10199.694009179737, 10199.898001020192, 10218.897811021794, 8606.956965215133, 8651.91348086511, 8705.695300664447, 8719.607617657093, 8886.955565222133, 7258.637068146466, 8433.915660843559, 8506.61720222579, 8524.531150786519, 8678.609462574073, 7646.8088297793, 7780.299773020455, 7792.337651299703, 7800.843983120192, 7820.84358312842, 5692.089265717465, 5778.422157784188, 5780.479756821906, 5919.7632094717455, 6024.668643224665, 5875.882482350415, 5947.672877991579, 6091.1472393864005, 6154.476869466145, 6174.475169610633], "value": 5067.306994814825, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.0, 371.95350581177354, 379.8689452139011, 384.2507111133291, 385.4449592586675, 4202.432671589335, 4362.825486980517, 4422.358757980095, 4895.902081958358, 5019.774110165043, 4633.397658304424, 4774.328984855299, 4876.463589005207, 4901.485344038869, 4955.430125535565, 3184.6274525478434, 4131.318332475138, 4170.020045289354, 4192.454980852485, 4204.705670603059, 3950.723449358546, 4088.2641124597562, 4117.9176416471655, 4182.769947652878, 4191.7484950903045, 3654.5979942206336, 3667.504886840269, 3889.2999260133165, 4201.390798334255, 4212.915741685165, 3635.745497815154, 3954.940675889864, 4186.748795072275, 4219.957800422003, 4386.135892245815, 3283.453869820588, 3698.14942563212, 3861.3049651062797, 3865.768053916774, 3867.690584753213, 4067.7355971861734, 4282.678799090081, 4304.481878101315, 4825.782839772183, 4837.298591704219, 4065.6544193743566, 4141.689373297015, 4675.812967481296, 4704.082703872747, 4866.150862672197, 4470.329450582439, 4512.510871412426, 5007.4241462231585, 5272.789088436458, 5368.436314187033, 4249.723767955073, 4267.722598031149, 4276.059266961263, 4284.943227250937, 4316.266234740102, 4737.620990320835, 4887.315775791392, 5456.543456543496, 5638.689872057035, 5661.348944871352, 3767.434884767307, 3821.885343439701, 3931.3513270310077, 4363.563643635674, 4458.7324760514475, 3835.8657446989223, 3905.6135072049583, 3945.546262179829, 4486.327050942385, 4506.481754598198, 4412.852658308862, 4480.372747815309, 4506.3465797459185, 4678.60231880294, 4821.855344339676, 4775.235962246094, 4982.676126051795, 5129.461406552333, 5284.207368894697, 5398.839249561363, 1358.395513996282, 1481.5925620454245, 1497.7458924319048, 1499.640086379283, 1506.246876561719, 972.4408465132577, 1008.8284991551454, 1046.874375074996, 1068.3429690740295, 1136.8858518651768, 4119.876403707894, 4121.732087414309, 4957.553820156131, 4966.95693904277, 5017.588040132745, 4625.676202665848, 5428.56571474289, 5585.218069470277, 5586.441355864381, 5597.916031259534, 4503.594676479132, 4552.93006143556, 4954.578860796801, 4976.979719157564, 5005.449400565934, 2318.6637937498967, 2532.5947848344176, 2876.280929767558, 2884.5096333623333, 2897.4350001749876, 5045.672031317952, 5070.490107396862, 5616.66300022, 5738.852229554106, 5770.567207459458, 4565.863024109282, 4892.804287828447, 4968.776405061781, 5095.770690318945, 5325.627206095536, 4886.315915771795, 4967.776450059684, 5639.182318563785, 5648.51987581052, 5753.942460575341, 4967.627427942846, 5191.870203244949, 5213.608979326642, 5440.972795135999, 5603.3836278008575, 5268.81559145428, 5364.865878353073, 5420.566354691537, 5428.782848686008, 5487.64330318528, 4793.579242482524, 4939.456659767495, 4993.675411098412, 5622.662640241599, 5689.008846903551, 4210.663146948175, 4379.781010949376, 4693.095714977924, 4778.617710583215, 4843.903121937471, 5116.948830511647, 5282.724710679403, 5444.972775136099, 5472.343318801769, 5525.530329921841, 5007.499250074963, 5102.183650615883, 5375.489328513785, 5415.241866138823, 5434.918476222781, 4421.381006659006, 4480.611010586728, 5273.762680679302, 5350.956563470086, 5400.297961265011, 4724.456687480983, 5015.523525265094, 5164.715940623302, 5246.580273578028, 5338.97330513345, 4634.675572709878, 4831.492693267156, 5448.564114870722, 5449.174526533656, 5450.700211488406, 4695.859124226278, 4761.547652972962, 4960.429550601727, 5316.680999140064, 5348.3954813555], "value": 4036.3936157254525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.8586525627504, 750.5421692767411, 754.5510421299329, 824.5300178898028, 840.8108175660475, 15352.923235383822, 16143.273552690127, 16179.919100404497, 16469.588260293498, 16507.50477485676, 14619.926900365483, 14964.551063468114, 15049.17229552377, 15110.0, 15779.526614201592, 925.1303774452027, 943.3915124744535, 947.1286416496839, 947.7014740356766, 948.0993056596227, 921.9907800922006, 935.3873213045466, 939.0140352629745, 952.9714108576719, 954.8281309364312, 995.9153471954893, 999.5801763259449, 1000.5697550053474, 1001.9549120289604, 1002.1381611813835, 991.4794732765283, 994.4679596415883, 994.7214779861614, 995.348047029197, 996.4419924842111, 739.4343327354578, 740.5038624121797, 740.9925900740977, 741.7478057460489, 742.8922806193071, 15337.539873803804, 15773.60565985848, 15816.920915395465, 16176.83823161765, 16442.177891105395, 13902.513412030632, 14167.0, 14185.936054795828, 14252.429902803875, 14288.142711437347, 11781.882181178164, 11824.704382390422, 11896.881031189663, 11942.880571194264, 12026.819597706042, 3605.2428989911887, 3648.0, 3652.8721494747556, 3659.7621154624862, 3717.8698745543775, 86220.0, 87580.0, 106704.4664776679, 109121.90878091197, 110488.89511104947, 10603.0, 10657.467126643636, 11013.00882920541, 11163.051140652973, 11434.02810761078, 20764.792352076587, 22447.663285050738, 22504.662430063563, 22704.432389190402, 22963.885180574325, 7869.527828330319, 7888.48724832884, 7963.8407231855035, 7985.361171106298, 7988.320992715568, 4271.209826182167, 4311.633511151587, 4353.978230108893, 4442.822287108479, 4550.49944506104, 3386.695197432243, 3448.689617934398, 3478.2174010847357, 3497.0, 3501.7373696972827, 602.6685323072293, 612.8467883029243, 622.4646803748773, 628.5882746800853, 628.8207860759661, 666.2604508995012, 679.9286074962157, 697.1912581405545, 705.108038331511, 718.81310859177, 10098.899011009942, 10125.392476451289, 10209.242364141126, 10456.68629941103, 10479.318844275098, 702.8840241360118, 703.8908969109796, 707.4552594502204, 710.9857802843915, 714.3606472207346, 4562.794674239667, 4901.705897646081, 4910.435299940553, 4920.163572192736, 4934.457209707001, 92874.53562732278, 96507.47327759794, 97368.0, 97744.53383199258, 100847.49576252072, 82753.0, 83617.58191209006, 84116.57941710252, 85086.14913850906, 85461.0, 72569.6371518139, 73844.5231095375, 74137.0, 74862.0, 77521.2247877514, 7642.656080476503, 11061.778764424505, 11268.323900565993, 11320.207585468937, 11360.715982100513, 45652.0, 45662.543374567154, 46287.0, 48927.0, 51286.23070654018, 63844.0, 63997.680011599645, 65985.68028639305, 66028.6698566504, 66576.6671166641, 12256.632301030982, 12564.434600442819, 12663.113582049536, 12694.619161425171, 12714.237145771283, 14255.928720356333, 14303.928480357532, 14384.928075359556, 14609.707805844037, 15111.395544178109, 13294.468221271041, 13460.798088028883, 14605.707885842436, 14659.70680586361, 14932.701345972802, 13757.10578812374, 13785.724285514434, 14354.3540540678, 14485.20331381742, 14852.331645076214, 13197.406116724962, 13706.040577159503, 13722.519711810039, 14013.159210447406, 14045.719085618026, 9653.276004299845, 9761.560729767036, 9966.700998970042, 10005.199584033406, 10072.0, 9935.950320248352, 10555.683329500127, 11001.22991390595, 11377.374244416636, 11523.423828808694], "value": 7632.043334581293, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1413.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 108.0, 145.0, 110.0, 109.0, 108.0, 108.0, 126.0, 110.0], "value": 117.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 182.0, 187.0, 185.0, 184.0, 181.0, 180.0, 184.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 62.0, 92.0, 64.0, 61.0, 61.0, 61.0, 77.0, 60.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 86.0, 69.0, 70.0, 69.0, 81.0, 70.0, 69.0, 84.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 87.0, 93.0, 136.0, 87.0, 86.0, 92.0, 136.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 93.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 83.0, 85.0, 84.0, 83.0, 86.0, 83.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 59.0, 64.0, 63.0, 63.0, 63.0, 63.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 54.0, 59.0, 64.0, 58.0, 57.0, 59.0, 57.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 83.0, 86.0, 85.0, 83.0, 85.0, 90.0, 84.0, 96.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 136.0, 134.0, 137.0, 135.0, 137.0, 141.0, 179.0, 139.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 166.0, 166.0, 165.0, 166.0, 167.0, 166.0, 164.0, 163.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 62.0, 67.0, 64.0, 67.0, 64.0, 64.0, 64.0], "value": 64.6, "unit": "score"}]}]} 08:23:24 INFO - Return code: 0 08:23:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:23:24 ERROR - # TBPL SUCCESS # 08:23:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:24 INFO - 2017-04-06 08:23:24.603 screenresolution[1651:9769] starting screenresolution argv=screenresolution get 08:23:24 INFO - 2017-04-06 08:23:24.605 screenresolution[1651:9769] Display 0: 1600x1200x32@60 08:23:24 INFO - 2017-04-06 08:23:24.611 screenresolution[1652:9772] starting screenresolution argv=screenresolution list 08:23:24 INFO - Available Modes on Display 0 08:23:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:24 INFO - Intel Iris: 08:23:24 INFO - Chipset Model: Intel Iris 08:23:24 INFO - Type: GPU 08:23:24 INFO - Bus: Built-In 08:23:24 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:24 INFO - Vendor: Intel (0x8086) 08:23:24 INFO - Device ID: 0x0a2e 08:23:24 INFO - Revision ID: 0x0009 08:23:24 INFO - Displays: 08:23:24 INFO - Display: 08:23:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:24 INFO - Display Serial Number: 1600x1200 60 08:23:24 INFO - Main Display: Yes 08:23:24 INFO - Mirror: Off 08:23:24 INFO - Online: Yes 08:23:24 INFO - Rotation: Supported 08:23:24 INFO - Return code: 0 08:23:24 INFO - Running post-action listener: _resource_record_post_action 08:23:24 INFO - [mozharness: 2017-04-06 15:23:24.849187Z] Finished run-tests step (success) 08:23:24 INFO - Running post-run listener: _resource_record_post_run 08:23:24 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 35229696; Write bytes: 509438976; Read time: 351; Write time: 1795 08:23:24 INFO - TinderboxPrint: CPU usage
53.7% 08:23:24 INFO - TinderboxPrint: I/O read bytes / time
35,229,696 / 351 08:23:24 INFO - TinderboxPrint: I/O write bytes / time
509,438,976 / 1,795 08:23:24 INFO - TinderboxPrint: CPU idle
2,224.7 (46.3%) 08:23:24 INFO - TinderboxPrint: CPU system
956.3 (19.9%) 08:23:24 INFO - TinderboxPrint: CPU user
1,628.7 (33.9%) 08:23:24 INFO - TinderboxPrint: Swap in / out
244,670,464 / 0 08:23:24 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199944704; Write bytes: 200861184; Read time: 15894; Write time: 408 08:23:24 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34263040; Write bytes: 298657280; Read time: 336; Write time: 1357 08:23:25 INFO - Running post-run listener: _upload_blobber_files 08:23:25 INFO - Blob upload gear active. 08:23:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:23:25 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 08:23:25 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'] 08:23:25 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 08:23:25 INFO - (blobuploader) - INFO - Open directory for files ... 08:23:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:23:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:23:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:26 INFO - (blobuploader) - INFO - Done attempting. 08:23:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:23:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:23:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:28 INFO - (blobuploader) - INFO - Done attempting. 08:23:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:23:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:23:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:29 INFO - (blobuploader) - INFO - Done attempting. 08:23:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:23:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:23:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:31 INFO - (blobuploader) - INFO - Done attempting. 08:23:31 INFO - (blobuploader) - INFO - Iteration through files over. 08:23:31 INFO - Return code: 0 08:23:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:23:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:23:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d93916fe491a52f1f344734f3c36f472f11f2aacc1561c1d48a673d88b3689cb6613a7be5de5f60c2494740ab76f5ab045582b53ebed3da90aef3979a3d6e7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57b1cedf77589059b319af7cc806bb35fbe4bb8b774b18314768cfc714860eb0fab0d4c000e051c82a0c2c6cf1b462515c03d2abb45b15d4362f874bf356cd27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be38725aded71c76f53b5466611a8e03141a6d1c2103b0782a0f92e050411a8a2280ed534129a354de3f445949add2269360dc4117a5422a0acc46d35c48fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dc7744061769fdc0fb27f9c4f5000f5663e8a994da024cdfa884533e9d832036285d499924dccc44e9e74c5fb3e62b47c9b593650cb61f9001e9fbb2b308b0"} 08:23:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:23:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:23:31 INFO - Contents: 08:23:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d93916fe491a52f1f344734f3c36f472f11f2aacc1561c1d48a673d88b3689cb6613a7be5de5f60c2494740ab76f5ab045582b53ebed3da90aef3979a3d6e7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57b1cedf77589059b319af7cc806bb35fbe4bb8b774b18314768cfc714860eb0fab0d4c000e051c82a0c2c6cf1b462515c03d2abb45b15d4362f874bf356cd27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be38725aded71c76f53b5466611a8e03141a6d1c2103b0782a0f92e050411a8a2280ed534129a354de3f445949add2269360dc4117a5422a0acc46d35c48fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dc7744061769fdc0fb27f9c4f5000f5663e8a994da024cdfa884533e9d832036285d499924dccc44e9e74c5fb3e62b47c9b593650cb61f9001e9fbb2b308b0"} 08:23:31 INFO - Running post-run listener: copy_logs_to_upload_dir 08:23:31 INFO - Copying logs to upload dir... 08:23:31 INFO - mkdir: /builds/slave/test/build/upload/logs 08:23:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.553418 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-06 08:23:31.897750) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 08:23:31.901339) ========= 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.CE7PLRABiz/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.o9XslWGTj6/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d93916fe491a52f1f344734f3c36f472f11f2aacc1561c1d48a673d88b3689cb6613a7be5de5f60c2494740ab76f5ab045582b53ebed3da90aef3979a3d6e7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57b1cedf77589059b319af7cc806bb35fbe4bb8b774b18314768cfc714860eb0fab0d4c000e051c82a0c2c6cf1b462515c03d2abb45b15d4362f874bf356cd27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be38725aded71c76f53b5466611a8e03141a6d1c2103b0782a0f92e050411a8a2280ed534129a354de3f445949add2269360dc4117a5422a0acc46d35c48fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dc7744061769fdc0fb27f9c4f5000f5663e8a994da024cdfa884533e9d832036285d499924dccc44e9e74c5fb3e62b47c9b593650cb61f9001e9fbb2b308b0"} build_url:https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011121 build_url: 'https://queue.taskcluster.net/v1/task/QtSVs3XrTTSBTorFU3YaHA/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d93916fe491a52f1f344734f3c36f472f11f2aacc1561c1d48a673d88b3689cb6613a7be5de5f60c2494740ab76f5ab045582b53ebed3da90aef3979a3d6e7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57b1cedf77589059b319af7cc806bb35fbe4bb8b774b18314768cfc714860eb0fab0d4c000e051c82a0c2c6cf1b462515c03d2abb45b15d4362f874bf356cd27", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/be38725aded71c76f53b5466611a8e03141a6d1c2103b0782a0f92e050411a8a2280ed534129a354de3f445949add2269360dc4117a5422a0acc46d35c48fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/78dc7744061769fdc0fb27f9c4f5000f5663e8a994da024cdfa884533e9d832036285d499924dccc44e9e74c5fb3e62b47c9b593650cb61f9001e9fbb2b308b0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 08:23:31.934890) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:23:31.935192) ========= 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.CE7PLRABiz/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.o9XslWGTj6/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.004886 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 08:23:31.983443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 08:23:31.983734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 08:23:31.984046) ========= ========= Total master_lag: 0.18 =========