builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0039 starttime: 1491556309.55 results: success (0) buildid: 20170407004017 builduid: 8579217f9ff94e9f8ccc0394919ba48e revision: 92ba21762445f89ae0691c4eab0746ca1cb819c2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.555068) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.555515) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.555789) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.583177) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.583591) ========= 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.Ep9V7It3Er/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.scCVat9P9f/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-07 02:11:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 11.3M=0.001s 2017-04-07 02:11:49 (11.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.239957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.848230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.848574) ========= 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.Ep9V7It3Er/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.scCVat9P9f/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.029913 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:49.900113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-07 02:11:49.900462) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 92ba21762445f89ae0691c4eab0746ca1cb819c2 --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 92ba21762445f89ae0691c4eab0746ca1cb819c2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ep9V7It3Er/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.scCVat9P9f/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-07 02:11:50,116 truncating revision to first 12 chars 2017-04-07 02:11:50,116 Setting DEBUG logging. 2017-04-07 02:11:50,116 attempt 1/10 2017-04-07 02:11:50,116 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/92ba21762445?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-07 02:11:54,089 unpacking tar archive at: mozilla-aurora-92ba21762445/testing/mozharness/ program finished with exit code 0 elapsedTime=4.249515 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-07 02:11:54.173392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:54.173711) ========= script_repo_revision: 92ba21762445f89ae0691c4eab0746ca1cb819c2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:54.174137) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:54.174402) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-07 02:11:54.194554) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2017-04-07 02:11:54.194961) ========= /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.Ep9V7It3Er/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.scCVat9P9f/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:11:54 INFO - MultiFileLogger online at 20170407 02:11:54 in /builds/slave/test 02:11:54 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 02:11:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:11:54 INFO - {'append_to_log': False, 02:11:54 INFO - 'base_work_dir': '/builds/slave/test', 02:11:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:11:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:11:54 INFO - 'branch': 'Mozilla-Aurora', 02:11:54 INFO - 'buildbot_json_path': 'buildprops.json', 02:11:54 INFO - 'config_files': ('talos/mac_config.py',), 02:11:54 INFO - 'default_actions': ('clobber', 02:11:54 INFO - 'read-buildbot-config', 02:11:54 INFO - 'download-and-extract', 02:11:54 INFO - 'populate-webroot', 02:11:54 INFO - 'create-virtualenv', 02:11:54 INFO - 'install', 02:11:54 INFO - 'run-tests'), 02:11:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:11:54 INFO - 'download_minidump_stackwalk': True, 02:11:54 INFO - 'download_symbols': 'ondemand', 02:11:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:11:54 INFO - 'tooltool.py': '/tools/tooltool.py', 02:11:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:11:54 INFO - '/tools/misc-python/virtualenv.py')}, 02:11:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:11:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:11:54 INFO - 'gecko_profile': False, 02:11:54 INFO - 'gecko_profile_interval': 0, 02:11:54 INFO - 'installer_path': 'installer.exe', 02:11:54 INFO - 'log_level': 'info', 02:11:54 INFO - 'log_name': 'talos', 02:11:54 INFO - 'log_to_console': True, 02:11:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:11:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:11:54 INFO - 'opt_config_files': (), 02:11:54 INFO - 'pip_index': False, 02:11:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:11:54 INFO - 'cmd': ('bash', 02:11:54 INFO - '-c', 02:11:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:11:54 INFO - 'enabled': True, 02:11:54 INFO - 'halt_on_failure': False, 02:11:54 INFO - 'name': 'check_screen_resolution'},), 02:11:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:11:54 INFO - 'cmd': ('bash', 02:11:54 INFO - '-c', 02:11:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:11:54 INFO - 'enabled': True, 02:11:54 INFO - 'halt_on_failure': False, 02:11:54 INFO - 'name': 'check_screen_resolution'},), 02:11:54 INFO - 'run_cmd_checks_enabled': True, 02:11:54 INFO - 'suite': 'dromaeojs', 02:11:54 INFO - 'system_bits': '32', 02:11:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:11:54 INFO - 'title': 't-yosemite-r7-0039', 02:11:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:11:54 INFO - 'use_talos_json': True, 02:11:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:11:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:11:54 INFO - 'work_dir': 'build'} 02:11:54 INFO - [mozharness: 2017-04-07 09:11:54.521207Z] Running clobber step. 02:11:54 INFO - Running pre-action listener: _resource_record_pre_action 02:11:54 INFO - Running main action method: clobber 02:11:54 INFO - rmtree: /builds/slave/test/build 02:11:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:11:57 INFO - Running post-action listener: _resource_record_post_action 02:11:57 INFO - [mozharness: 2017-04-07 09:11:57.174478Z] Finished clobber step (success) 02:11:57 INFO - [mozharness: 2017-04-07 09:11:57.174601Z] Running read-buildbot-config step. 02:11:57 INFO - Running pre-action listener: _resource_record_pre_action 02:11:57 INFO - Running main action method: read_buildbot_config 02:11:57 INFO - Using buildbot properties: 02:11:57 INFO - { 02:11:57 INFO - "project": "", 02:11:57 INFO - "product": "firefox", 02:11:57 INFO - "script_repo_revision": "production", 02:11:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:11:57 INFO - "repository": "", 02:11:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:11:57 INFO - "buildid": "20170407004017", 02:11:57 INFO - "stage_platform": "macosx64", 02:11:57 INFO - "basedir": "/builds/slave/test", 02:11:57 INFO - "nightly_build": "True", 02:11:57 INFO - "buildnumber": 91, 02:11:57 INFO - "slavename": "t-yosemite-r7-0039", 02:11:57 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:11:57 INFO - "platform": "yosemite_r7", 02:11:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:11:57 INFO - "branch": "mozilla-aurora", 02:11:57 INFO - "repo_path": "releases/mozilla-aurora", 02:11:57 INFO - "revision": "92ba21762445f89ae0691c4eab0746ca1cb819c2", 02:11:57 INFO - "pgo_build": "False", 02:11:57 INFO - "builduid": "8579217f9ff94e9f8ccc0394919ba48e", 02:11:57 INFO - "slavebuilddir": "test" 02:11:57 INFO - } 02:11:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:11:57 INFO - Running post-action listener: _resource_record_post_action 02:11:57 INFO - [mozharness: 2017-04-07 09:11:57.177963Z] Finished read-buildbot-config step (success) 02:11:57 INFO - [mozharness: 2017-04-07 09:11:57.178075Z] Running download-and-extract step. 02:11:57 INFO - Running pre-action listener: _resource_record_pre_action 02:11:57 INFO - Running main action method: download_and_extract 02:11:57 INFO - mkdir: /builds/slave/test/build/tests 02:11: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')]} 02:11:57 INFO - trying https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:11:57 INFO - Downloading https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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 02:11:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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 02:11:59 INFO - Downloaded 1199 bytes. 02:11:59 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:11:59 INFO - Using the following test package requirements: 02:11:59 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:11:59 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:11:59 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:11:59 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'jsshell-mac.zip'], 02:11:59 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:11:59 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:11:59 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:11:59 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:11:59 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:11:59 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:11:59 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:11:59 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:11:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:11:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:11:59 INFO - Fetch https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:12:01 INFO - Expected file size: 51790493 02:12:01 INFO - Obtained file size: 51790493 02:12:17 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 02:12:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:12:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:12:17 INFO - Fetch https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:12:19 INFO - Expected file size: 51790493 02:12:19 INFO - Obtained file size: 51790493 02:12:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:12:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:12:34 INFO - Fetch https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:12:35 INFO - Expected file size: 14404424 02:12:35 INFO - Obtained file size: 14404424 02:12:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:36 INFO - trying https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:12:36 INFO - Downloading https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:12:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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 02:12:39 INFO - Downloaded 74700464 bytes. 02:12:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:12:39 INFO - mkdir: /builds/slave/test/properties 02:12:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:12:39 INFO - Writing to file /builds/slave/test/properties/build_url 02:12:39 INFO - Contents: 02:12:39 INFO - build_url:https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:12:41 INFO - Running post-action listener: _resource_record_post_action 02:12:41 INFO - Running post-action listener: set_extra_try_arguments 02:12:41 INFO - [mozharness: 2017-04-07 09:12:41.720253Z] Finished download-and-extract step (success) 02:12:41 INFO - [mozharness: 2017-04-07 09:12:41.720377Z] Running populate-webroot step. 02:12:41 INFO - Running pre-action listener: _resource_record_pre_action 02:12:41 INFO - Running main action method: populate_webroot 02:12:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:12:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:12:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:12:41 INFO - '--tppagecycles', 02:12:41 INFO - '7'], 02:12:41 INFO - 'tests': ['tsvgm']}}, 02:12:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:12:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:12:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:12:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:12:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:12:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:12:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['damp', 'tps']}, 02:12:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'tests': ['damp', 'tps']}, 02:12:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['dromaeo_dom']}, 02:12:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:12:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['basic_compositor_video']}, 02:12:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:12:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['a11yr', 02:12:41 INFO - 'ts_paint', 02:12:41 INFO - 'tpaint', 02:12:41 INFO - 'sessionrestore', 02:12:41 INFO - 'sessionrestore_no_auto_restore', 02:12:41 INFO - 'tabpaint']}, 02:12:41 INFO - 'other-e10s': {'tests': ['a11yr', 02:12:41 INFO - 'ts_paint', 02:12:41 INFO - 'tpaint', 02:12:41 INFO - 'sessionrestore', 02:12:41 INFO - 'sessionrestore_no_auto_restore', 02:12:41 INFO - 'tabpaint']}, 02:12:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:12:41 INFO - 'ts_paint', 02:12:41 INFO - 'tpaint', 02:12:41 INFO - 'sessionrestore', 02:12:41 INFO - 'sessionrestore_no_auto_restore', 02:12:41 INFO - 'tabpaint']}, 02:12:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:12:41 INFO - 'ts_paint', 02:12:41 INFO - 'tpaint', 02:12:41 INFO - 'sessionrestore', 02:12:41 INFO - 'sessionrestore_no_auto_restore', 02:12:41 INFO - 'tabpaint']}, 02:12:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['a11yr', 02:12:41 INFO - 'ts_paint', 02:12:41 INFO - 'tpaint', 02:12:41 INFO - 'sessionrestore', 02:12:41 INFO - 'sessionrestore_no_auto_restore', 02:12:41 INFO - 'tabpaint']}, 02:12:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['a11yr', 02:12:41 INFO - 'ts_paint', 02:12:41 INFO - 'tpaint', 02:12:41 INFO - 'sessionrestore', 02:12:41 INFO - 'sessionrestore_no_auto_restore', 02:12:41 INFO - 'tabpaint']}, 02:12:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['tsvgx', 02:12:41 INFO - 'tsvgr_opacity', 02:12:41 INFO - 'tart', 02:12:41 INFO - 'tscrollx', 02:12:41 INFO - 'cart', 02:12:41 INFO - 'tsvg_static']}, 02:12:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:12:41 INFO - 'tsvgr_opacity', 02:12:41 INFO - 'tart', 02:12:41 INFO - 'tscrollx', 02:12:41 INFO - 'cart', 02:12:41 INFO - 'tsvg_static']}, 02:12:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'talos_options': ['--disable-e10s'], 02:12:41 INFO - 'tests': ['tp5o']}, 02:12:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:12:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'talos_options': ['--disable-e10s', 02:12:41 INFO - '--xperf_path', 02:12:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:12:41 INFO - 'tests': ['tp5n']}, 02:12:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:12:41 INFO - 'talos_options': ['--xperf_path', 02:12:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:12:41 INFO - 'tests': ['tp5n']}}, 02:12:41 INFO - 'talos.zip': {'path': '', 02:12:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:12:41 INFO - Running post-action listener: _resource_record_post_action 02:12:41 INFO - [mozharness: 2017-04-07 09:12:41.738646Z] Finished populate-webroot step (success) 02:12:41 INFO - [mozharness: 2017-04-07 09:12:41.738783Z] Running create-virtualenv step. 02:12:41 INFO - Running pre-action listener: _resource_record_pre_action 02:12:41 INFO - Running main action method: create_virtualenv 02:12:41 INFO - Creating virtualenv /builds/slave/test/build/venv 02:12:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:12:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:12:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:12:41 INFO - Using real prefix '/tools/python27' 02:12:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:12:42 INFO - Installing distribute.............................................................................................................................................................................................done. 02:12:45 INFO - Installing pip.................done. 02:12:45 INFO - Return code: 0 02:12:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:12:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:12:45 INFO - Reading from file tmpfile_stdout 02:12:45 INFO - Output received: 02:12:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:12:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:12: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')]} 02:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:47 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:47 INFO - 'HOME': '/Users/cltbld', 02:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:47 INFO - 'LOGNAME': 'cltbld', 02:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:47 INFO - 'MOZ_NO_REMOTE': '1', 02:12:47 INFO - 'NO_EM_RESTART': '1', 02:12:47 INFO - 'PAGER': '/bin/cat', 02:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:47 INFO - 'PWD': '/builds/slave/test', 02:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:47 INFO - 'SHELL': '/bin/bash', 02:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:12:47 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:47 INFO - 'USER': 'cltbld', 02:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:47 INFO - 'XPC_FLAGS': '0x0', 02:12:47 INFO - 'XPC_SERVICE_NAME': '0', 02:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:12:47 INFO - Cleaning up... 02:12:47 INFO - Return code: 0 02:12:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:47 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:47 INFO - 'HOME': '/Users/cltbld', 02:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:47 INFO - 'LOGNAME': 'cltbld', 02:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:47 INFO - 'MOZ_NO_REMOTE': '1', 02:12:47 INFO - 'NO_EM_RESTART': '1', 02:12:47 INFO - 'PAGER': '/bin/cat', 02:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:47 INFO - 'PWD': '/builds/slave/test', 02:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:47 INFO - 'SHELL': '/bin/bash', 02:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:12:47 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:47 INFO - 'USER': 'cltbld', 02:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:47 INFO - 'XPC_FLAGS': '0x0', 02:12:47 INFO - 'XPC_SERVICE_NAME': '0', 02:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:48 INFO - Downloading/unpacking psutil>=3.1.1 02:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:12:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:12:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:12:55 INFO - Installing collected packages: psutil 02:12:55 INFO - Running setup.py install for psutil 02:12:55 INFO - building 'psutil._psutil_osx' extension 02:12:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:12:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:12:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:12:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:12:56 INFO - building 'psutil._psutil_posix' extension 02:12:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:12:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:12:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:12:56 INFO - ^ 02:12:56 INFO - 1 warning generated. 02:12:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:12:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:12:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:12:56 INFO - Successfully installed psutil 02:12:56 INFO - Cleaning up... 02:12:56 INFO - Return code: 0 02:12:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:12:56 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:12:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:12:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:12:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:12:56 INFO - 'HOME': '/Users/cltbld', 02:12:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:12:56 INFO - 'LOGNAME': 'cltbld', 02:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:12:56 INFO - 'MOZ_NO_REMOTE': '1', 02:12:56 INFO - 'NO_EM_RESTART': '1', 02:12:56 INFO - 'PAGER': '/bin/cat', 02:12:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:12:56 INFO - 'PWD': '/builds/slave/test', 02:12:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:12:56 INFO - 'SHELL': '/bin/bash', 02:12:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:12:56 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:12:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:12:56 INFO - 'USER': 'cltbld', 02:12:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:12:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:12:56 INFO - 'XPC_FLAGS': '0x0', 02:12:56 INFO - 'XPC_SERVICE_NAME': '0', 02:12:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:12:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:12:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:12:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:13:01 INFO - Installing collected packages: mozsystemmonitor 02:13:01 INFO - Running setup.py install for mozsystemmonitor 02:13:01 INFO - Successfully installed mozsystemmonitor 02:13:01 INFO - Cleaning up... 02:13:01 INFO - Return code: 0 02:13:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:13: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')]} 02:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13: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')]} 02:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13: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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13: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 02:13: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 02:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:01 INFO - 'HOME': '/Users/cltbld', 02:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:01 INFO - 'LOGNAME': 'cltbld', 02:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:01 INFO - 'MOZ_NO_REMOTE': '1', 02:13:01 INFO - 'NO_EM_RESTART': '1', 02:13:01 INFO - 'PAGER': '/bin/cat', 02:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:01 INFO - 'PWD': '/builds/slave/test', 02:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:01 INFO - 'SHELL': '/bin/bash', 02:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:01 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:01 INFO - 'USER': 'cltbld', 02:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:01 INFO - 'XPC_FLAGS': '0x0', 02:13:01 INFO - 'XPC_SERVICE_NAME': '0', 02:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:01 INFO - Downloading/unpacking jsonschema==2.5.1 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:13:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13: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 02:13:06 INFO - Downloading functools32-3.2.3-2.tar.gz 02:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:13:06 INFO - warning: no files found matching '*.txt' 02:13:06 INFO - no previously-included directories found matching 'build' 02:13:06 INFO - no previously-included directories found matching 'dist' 02:13:06 INFO - no previously-included directories found matching '.git*' 02:13:06 INFO - Installing collected packages: jsonschema, functools32 02:13:07 INFO - Running setup.py install for functools32 02:13:07 INFO - warning: no files found matching '*.txt' 02:13:07 INFO - no previously-included directories found matching 'build' 02:13:07 INFO - no previously-included directories found matching 'dist' 02:13:07 INFO - no previously-included directories found matching '.git*' 02:13:07 INFO - Successfully installed jsonschema functools32 02:13:07 INFO - Cleaning up... 02:13:07 INFO - Return code: 0 02:13:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:07 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:07 INFO - 'HOME': '/Users/cltbld', 02:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:07 INFO - 'LOGNAME': 'cltbld', 02:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:07 INFO - 'MOZ_NO_REMOTE': '1', 02:13:07 INFO - 'NO_EM_RESTART': '1', 02:13:07 INFO - 'PAGER': '/bin/cat', 02:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:07 INFO - 'PWD': '/builds/slave/test', 02:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:07 INFO - 'SHELL': '/bin/bash', 02:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:07 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:07 INFO - 'USER': 'cltbld', 02:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:07 INFO - 'XPC_FLAGS': '0x0', 02:13:07 INFO - 'XPC_SERVICE_NAME': '0', 02:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:13:07 INFO - Cleaning up... 02:13:07 INFO - Return code: 0 02:13:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:07 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:07 INFO - 'HOME': '/Users/cltbld', 02:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:07 INFO - 'LOGNAME': 'cltbld', 02:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:07 INFO - 'MOZ_NO_REMOTE': '1', 02:13:07 INFO - 'NO_EM_RESTART': '1', 02:13:07 INFO - 'PAGER': '/bin/cat', 02:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:07 INFO - 'PWD': '/builds/slave/test', 02:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:07 INFO - 'SHELL': '/bin/bash', 02:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:07 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:07 INFO - 'USER': 'cltbld', 02:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:07 INFO - 'XPC_FLAGS': '0x0', 02:13:07 INFO - 'XPC_SERVICE_NAME': '0', 02:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:10 INFO - Downloading blobuploader-1.2.4.tar.gz 02:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:13:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:13:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:11 INFO - Downloading docopt-0.6.1.tar.gz 02:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:13:11 INFO - Installing collected packages: blobuploader, requests, docopt 02:13:11 INFO - Running setup.py install for blobuploader 02:13:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:13:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:13:12 INFO - Running setup.py install for requests 02:13:12 INFO - Running setup.py install for docopt 02:13:12 INFO - Successfully installed blobuploader requests docopt 02:13:12 INFO - Cleaning up... 02:13:12 INFO - Return code: 0 02:13:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:12 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:13:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:12 INFO - 'HOME': '/Users/cltbld', 02:13:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:12 INFO - 'LOGNAME': 'cltbld', 02:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:12 INFO - 'MOZ_NO_REMOTE': '1', 02:13:12 INFO - 'NO_EM_RESTART': '1', 02:13:12 INFO - 'PAGER': '/bin/cat', 02:13:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:12 INFO - 'PWD': '/builds/slave/test', 02:13:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:12 INFO - 'SHELL': '/bin/bash', 02:13:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:12 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:12 INFO - 'USER': 'cltbld', 02:13:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:12 INFO - 'XPC_FLAGS': '0x0', 02:13:12 INFO - 'XPC_SERVICE_NAME': '0', 02:13:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-DtkYYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-eOYYrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ReexED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-8SzhN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-7rn1Mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-PRgkfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-mFfhUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-72CBjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:13:13 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-QUyNYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-5JlcI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-5Hxvvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-8UeanS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-K5Xuhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-OMmn1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-teG0g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-karhNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:13:14 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-w2DPTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:13:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:13:14 INFO - Running setup.py install for manifestparser 02:13:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:13:15 INFO - Running setup.py install for mozcrash 02:13:15 INFO - Running setup.py install for mozdebug 02:13:15 INFO - Running setup.py install for mozdevice 02:13:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:13:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:13:15 INFO - Running setup.py install for mozfile 02:13:15 INFO - Running setup.py install for mozhttpd 02:13:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:13:15 INFO - Running setup.py install for mozinfo 02:13:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:13:15 INFO - Running setup.py install for mozInstall 02:13:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Running setup.py install for mozleak 02:13:16 INFO - Running setup.py install for mozlog 02:13:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Running setup.py install for moznetwork 02:13:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Running setup.py install for mozprocess 02:13:16 INFO - Running setup.py install for mozprofile 02:13:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:13:16 INFO - Running setup.py install for mozrunner 02:13:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:13:17 INFO - Running setup.py install for mozscreenshot 02:13:17 INFO - Running setup.py install for moztest 02:13:17 INFO - Running setup.py install for mozversion 02:13:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:13:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:13:17 INFO - Cleaning up... 02:13:17 INFO - Return code: 0 02:13:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:17 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:17 INFO - 'HOME': '/Users/cltbld', 02:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:17 INFO - 'LOGNAME': 'cltbld', 02:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:17 INFO - 'MOZ_NO_REMOTE': '1', 02:13:17 INFO - 'NO_EM_RESTART': '1', 02:13:17 INFO - 'PAGER': '/bin/cat', 02:13:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:17 INFO - 'PWD': '/builds/slave/test', 02:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:17 INFO - 'SHELL': '/bin/bash', 02:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:17 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:17 INFO - 'USER': 'cltbld', 02:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:17 INFO - 'XPC_FLAGS': '0x0', 02:13:17 INFO - 'XPC_SERVICE_NAME': '0', 02:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:13:17 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-d61yJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:13:17 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-TAXnFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:13:17 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-mq8K8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-dLwJ8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Ew2tOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-MLqKOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-eRgEUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-lo4m3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-iCH5yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-f7SqUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Rl2cDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:13:18 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-eCHhPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:13:19 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)) 02:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:13:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-YFgbfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:13:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-b87cyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-oBbisJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:13:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-x1ypHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:13:19 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Tja4X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:13:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:23 INFO - Downloading blessings-1.6.tar.gz 02:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:13:23 INFO - Installing collected packages: blessings 02:13:23 INFO - Running setup.py install for blessings 02:13:24 INFO - Successfully installed blessings 02:13:24 INFO - Cleaning up... 02:13:24 INFO - Return code: 0 02:13:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:13:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:13:24 INFO - Reading from file tmpfile_stdout 02:13:24 INFO - Current package versions: 02:13:24 INFO - blessings == 1.6 02:13:24 INFO - blobuploader == 1.2.4 02:13:24 INFO - docopt == 0.6.1 02:13:24 INFO - functools32 == 3.2.3-2 02:13:24 INFO - jsonschema == 2.5.1 02:13:24 INFO - manifestparser == 1.1 02:13:24 INFO - mozInstall == 1.12 02:13:24 INFO - mozcrash == 1.0 02:13:24 INFO - mozdebug == 0.1 02:13:24 INFO - mozdevice == 0.49 02:13:24 INFO - mozfile == 1.2 02:13:24 INFO - mozhttpd == 0.7 02:13:24 INFO - mozinfo == 0.9 02:13:24 INFO - mozleak == 0.1 02:13:24 INFO - mozlog == 3.4 02:13:24 INFO - moznetwork == 0.27 02:13:24 INFO - mozprocess == 0.25 02:13:24 INFO - mozprofile == 0.28 02:13:24 INFO - mozrunner == 6.13 02:13:24 INFO - mozscreenshot == 0.1 02:13:24 INFO - mozsystemmonitor == 0.3 02:13:24 INFO - moztest == 0.8 02:13:24 INFO - mozversion == 1.4 02:13:24 INFO - psutil == 3.1.1 02:13:24 INFO - requests == 1.2.3 02:13:24 INFO - wsgiref == 0.1.2 02:13:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:24 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:24 INFO - 'HOME': '/Users/cltbld', 02:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:24 INFO - 'LOGNAME': 'cltbld', 02:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:24 INFO - 'MOZ_NO_REMOTE': '1', 02:13:24 INFO - 'NO_EM_RESTART': '1', 02:13:24 INFO - 'PAGER': '/bin/cat', 02:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:24 INFO - 'PWD': '/builds/slave/test', 02:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:24 INFO - 'SHELL': '/bin/bash', 02:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:24 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:24 INFO - 'USER': 'cltbld', 02:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:24 INFO - 'XPC_FLAGS': '0x0', 02:13:24 INFO - 'XPC_SERVICE_NAME': '0', 02:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:13:24 INFO - Cleaning up... 02:13:24 INFO - Return code: 0 02:13:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:13:24 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 0x1064fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcca25a3440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, '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.Ep9V7It3Er/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.scCVat9P9f/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:13:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:24 INFO - 'HOME': '/Users/cltbld', 02:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:24 INFO - 'LOGNAME': 'cltbld', 02:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:24 INFO - 'MOZ_NO_REMOTE': '1', 02:13:24 INFO - 'NO_EM_RESTART': '1', 02:13:24 INFO - 'PAGER': '/bin/cat', 02:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:24 INFO - 'PWD': '/builds/slave/test', 02:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:24 INFO - 'SHELL': '/bin/bash', 02:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:24 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:24 INFO - 'USER': 'cltbld', 02:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:24 INFO - 'XPC_FLAGS': '0x0', 02:13:24 INFO - 'XPC_SERVICE_NAME': '0', 02:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:13:24 INFO - Cleaning up... 02:13:24 INFO - Return code: 0 02:13:24 INFO - Running post-action listener: _resource_record_post_action 02:13:24 INFO - Running post-action listener: _start_resource_monitoring 02:13:24 INFO - Starting resource monitoring. 02:13:24 INFO - [mozharness: 2017-04-07 09:13:24.985763Z] Finished create-virtualenv step (success) 02:13:24 INFO - [mozharness: 2017-04-07 09:13:24.986775Z] Running install step. 02:13:24 INFO - Running pre-action listener: _resource_record_pre_action 02:13:24 INFO - Running main action method: install 02:13:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:13:25 INFO - Reading from file tmpfile_stdout 02:13:25 INFO - Detecting whether we're running mozinstall >=1.0... 02:13:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:13:25 INFO - Reading from file tmpfile_stdout 02:13:25 INFO - Output received: 02:13:25 INFO - Usage: mozinstall [options] installer 02:13:25 INFO - Options: 02:13:25 INFO - -h, --help show this help message and exit 02:13:25 INFO - -d DEST, --destination=DEST 02:13:25 INFO - Directory to install application into. [default: 02:13:25 INFO - "/builds/slave/test"] 02:13:25 INFO - --app=APP Application being installed. [default: firefox] 02:13:25 INFO - mkdir: /builds/slave/test/build/application 02:13:25 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'] 02:13:25 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 02:13:45 INFO - Reading from file tmpfile_stdout 02:13:45 INFO - Output received: 02:13:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:13:45 INFO - Running post-action listener: _resource_record_post_action 02:13:45 INFO - [mozharness: 2017-04-07 09:13:45.357337Z] Finished install step (success) 02:13:45 INFO - [mozharness: 2017-04-07 09:13:45.357483Z] Running run-tests step. 02:13:45 INFO - Running pre-action listener: _resource_record_pre_action 02:13:45 INFO - Running main action method: run_tests 02:13:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:13:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:13:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:13:45 INFO - 2017-04-07 02:13:45.372 screenresolution[1626:7637] starting screenresolution argv=screenresolution get 02:13:45 INFO - 2017-04-07 02:13:45.375 screenresolution[1626:7637] Display 0: 1600x1200x32@60 02:13:45 INFO - 2017-04-07 02:13:45.380 screenresolution[1627:7640] starting screenresolution argv=screenresolution list 02:13:45 INFO - Available Modes on Display 0 02:13:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:13:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:13:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:13:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:13:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:13:45 INFO - Intel Iris: 02:13:45 INFO - Chipset Model: Intel Iris 02:13:45 INFO - Type: GPU 02:13:45 INFO - Bus: Built-In 02:13:45 INFO - VRAM (Dynamic, Max): 1536 MB 02:13:45 INFO - Vendor: Intel (0x8086) 02:13:45 INFO - Device ID: 0x0a2e 02:13:45 INFO - Revision ID: 0x0009 02:13:45 INFO - Displays: 02:13:45 INFO - Display: 02:13:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:13:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:13:45 INFO - Display Serial Number: 1600x1200 60 02:13:45 INFO - Main Display: Yes 02:13:45 INFO - Mirror: Off 02:13:45 INFO - Online: Yes 02:13:45 INFO - Rotation: Supported 02:13:45 INFO - Return code: 0 02:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:13:45 INFO - Python 2.7.3 02:13:45 INFO - Return code: 0 02:13:45 INFO - grabbing minidump binary from tooltool 02:13: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')]} 02:13:45 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 0x1062dad30>, '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 0x106201db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1062cfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:13:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:13:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:13:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:13:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:13:45 INFO - Return code: 0 02:13:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:13:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:13:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:13:45 INFO - ENV: RUST_BACKTRACE is now 1 02:13:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:13:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:13:45 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/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 02:13:45 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/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --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 02:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ep9V7It3Er/Render', 02:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:45 INFO - 'HOME': '/Users/cltbld', 02:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:45 INFO - 'LOGNAME': 'cltbld', 02:13:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:13:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:45 INFO - 'MOZ_NO_REMOTE': '1', 02:13:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:13:45 INFO - 'NO_EM_RESTART': '1', 02:13:45 INFO - 'PAGER': '/bin/cat', 02:13:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:45 INFO - 'PWD': '/builds/slave/test', 02:13:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:45 INFO - 'RUST_BACKTRACE': '1', 02:13:45 INFO - 'SHELL': '/bin/bash', 02:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scCVat9P9f/Listeners', 02:13:45 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 02:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:45 INFO - 'USER': 'cltbld', 02:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:45 INFO - 'XPC_FLAGS': '0x0', 02:13:45 INFO - 'XPC_SERVICE_NAME': '0', 02:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:45 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/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--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 02:13:45 INFO - mozversion application_buildid: 20170407004017 02:13:45 INFO - mozversion application_changeset: 92ba21762445f89ae0691c4eab0746ca1cb819c2 02:13:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:13:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:13:45 INFO - mozversion application_name: Firefox 02:13:45 INFO - mozversion application_remotingname: firefox-dev 02:13:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:13:45 INFO - mozversion application_vendor: Mozilla 02:13:45 INFO - mozversion application_version: 54.0a2 02:13:45 INFO - mozversion platform_buildid: 20170407004017 02:13:45 INFO - mozversion platform_changeset: 92ba21762445f89ae0691c4eab0746ca1cb819c2 02:13:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:13:45 INFO - mozversion platform_version: 54.0a2 02:13:45 INFO - using testdate: 1491556425 02:13:45 INFO - actual date: 1491556425 02:13:45 INFO - starting webserver on 'localhost:49241' 02:13:45 INFO - SUITE-START | Running 2 tests 02:13:45 INFO - TEST-START | dromaeo_css 02:13:45 INFO - operating with platform_type : mac_ 02:13:45 INFO - Initialising browser for dromaeo_css test... 02:13:46 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpK6rLek/profile http://localhost:49241/getInfo.html) 02:13:48 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 02:13:48 INFO - PROCESS | 1637 | colorDepth:24 02:13:48 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 02:13:48 INFO - PROCESS | 1637 | __metrics 02:13:48 INFO - PROCESS | 1637 | 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" 02:13:48 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:13:49 INFO - TEST-INFO | 1637: exit 0 02:13:49 INFO - Browser initialized. 02:13:49 INFO - Running cycle 1/1 for dromaeo_css test... 02:13:49 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpK6rLek/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:14:01 INFO - PROCESS | 1640 | RSS: Main: 210731008 02:14:01 INFO - PROCESS | 1640 | 02:17:06 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:17:07 INFO - PROCESS | 1640 | RSS: Main: 374366208 02:17:07 INFO - PROCESS | 1640 | 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 02:18:50 INFO - PROCESS | 1640 | 02:18:50 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:50 INFO - PROCESS | 1640 | Full stack: 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 02:18:50 INFO - PROCESS | 1640 | 02:18:50 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:50 INFO - PROCESS | 1640 | Full stack: 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 02:18:50 INFO - PROCESS | 1640 | 02:18:50 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:50 INFO - PROCESS | 1640 | Full stack: 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | ************************* 02:18:50 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 02:18:50 INFO - PROCESS | 1640 | 02:18:50 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:18:50 INFO - PROCESS | 1640 | Full stack: 02:18:50 INFO - PROCESS | 1640 | ************************* 02:20:10 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:20:11 INFO - PROCESS | 1640 | RSS: Main: 348778496 02:20:11 INFO - PROCESS | 1640 | 02:23:14 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:23:14 INFO - PROCESS | 1640 | RSS: Main: 324714496 02:23:14 INFO - PROCESS | 1640 | 02:26:17 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:26:17 INFO - PROCESS | 1640 | RSS: Main: 321216512 02:26:17 INFO - PROCESS | 1640 | 02:29:21 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:29:22 INFO - PROCESS | 1640 | RSS: Main: 321732608 02:29:22 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:32:25 INFO - PROCESS | 1640 | RSS: Main: 411865088 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | __start_tp_report 02:32:25 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 02:32:25 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 02:32:25 INFO - PROCESS | 1640 | |i|pagename|runs| 02:32:25 INFO - PROCESS | 1640 | |0;dojo.html;3403.625601183871;3419.81191034493;3423.383790917635;3435.0210190095827;3435.2099017226037;6004.129401236818;6050.394960503947;6061.2120424344885;6074.878502429949;6105.450509454148;6052.818415447544;6107.328193898678;6126.081087836816;6135.81592552223;6138.693065346726;5430.782768689248;5465.617406781527;5494.230807686927;5496.972515137419;5574.498295153456;5344.599155063366;5347.41178470368;5559.305086864142;5574.331080270374;5597.188407680904;5383.515483606475;5431.375391829931;5438.91841622376;5445.646033007862;5453.045716999529;5435.157550579666;5437.456254374571;5448.318960129984;5449.04641687705;5450.618456707993;5245.973770131163;5388.326459192601;5392.299001129828;5396.514313711785;5418.945810541923;6033.396660333975;6039.003564411867;6047.969760151171;6048.455638992512;6048.5463590230465;6249.281332646713;6252.06219067139;6275.466585340251;6281;6304.306526282106;5345.7594408251725;6176.444120029174;6219;6238.750449981995;6345.714442850082;6066.4540191382985;6129.7854575090105;6130.2030736004035;6153;6153.384661533855;6531.640759758259;6596.736130554772;6603.3396660334065;6612.107365505595;6623.966880165664;5148.897022059537;5190.2993095932325;6217.471514921192;6254.6872656366995;6269.529785266123;6421.229452465734;6461.903071453838;6477.5465717400275;6477.805665830033;6483.384078512534;6232.407921247474;6377.585456945284;6377.776777812847;6384.904226436607;6393.744250230032;6153.815385538441;6211.596246243979;6272.3100458949475;6356.841078973062;6402.167718196698;5146.279520867081;5184.326037615161;5191.662541934762;5196.064708352533;5226.660267082703;4567.840125595588;4573.748443835621;4596.35651008859;4613.215753321876;4634.745089020069;5827.125931110282;5919.378465261172;6118.388161183846;6119.571629985859;6130.754769809247;6266.811995640138;6394.23269207698;6436.774712885027;6484;6490;6074.51403887688;6116.571839971249;6130.570860039755;6139.508839292936;6139.539534534928;5676.205331253545;5711.343195532484;5719.170720245541;5726.112452569859;5736.741846616912;6339.587926784744;6344.175257216532;6458.063580780667;6625.370589793962;6686.264510903699;6493.123428337114;6518.73925042993;6529.444997175293;6529.7388104475285;6535.738570457129;6686.030525573668;6688.966555167193;6724.226713927766;6744.123263975652;6774.153230846145;6253.218347706536;6265.780697675559;6290.370962903673;6326.240851097898;6410.935890641033;6268.749250029948;6406.519511036784;6412.647304398303;6425.228972523327;6426.710798014007;6310.905336419865;6311.684415779285;6315.210598675166;6315.27374351937;6315.778947736807;5635.887282254414;5991.191189189403;6166.722497487534;6223.502119830312;6248.312685604489;6200.937990620217;6275.623462592259;6285.59143655661;6287.30839607652;6293.653849038347;6263.96868015657;6275.466585340115;6300;6308.842278943063;6315.147455093504;6066.18106555627;6107.267127944675;6117.96941015292;6189.35011823752;6238.313785483684 02:32:25 INFO - PROCESS | 1640 | |1;ext.html;741.2846603028077;782.7299581644332;803.3813963248301;863.9827203455931;875.7591662292873;28118.156455306347;40164.79917600412;40730.18539629206;40801.387979180334;40935;41677.791611041976;41923.16153676924;41954.37068443976;42053.94865128373;42118;7430.405567554582;9319.394239374453;9487.383320084207;9499.097585729345;9507.286953478482;7501.0248667673395;11033.282836615634;11310.208285420023;11413.25813822103;11435.256708313975;11279.154063445234;11291.322520648788;11309.717257068598;11323.32060076394;11333.546658133664;11259.718507037307;11286;11287.266327688674;11292.887071129266;11315.943420282929;11030.448477576088;11085.944570277177;11095.50070246841;11096;11120.944395278053;39188.608113919065;39470.605293947265;39476;39507.60492395097;39516.80241598774;3769.5664998525244;3803.5055442792536;3820.3505404081375;3842.269968705965;3863.2466668999555;27502.17493475199;29306.853465732747;29577.55633665497;29668.70331296681;29798.255043623867;2937.3244153844735;3038.6657467678538;3046.923826904323;3125.8749650013974;3155.7159855612877;372052;372863.27136728825;373324;374261;375504;53018;53023;53043.93912121736;53093.938121237356;53106;54729.90540189174;54849.45150548523;55108.17337739937;55191;55193.72403138039;14839.777403338958;14967.550973470814;14967.925160374347;14981.850181498046;14989.925050374679;10927.617533386452;11176.776464470666;12818.423170957329;12840.165389249669;12869.871301286867;10928.235023548434;12244.142909996215;12530.749385012248;12553.372331383394;12776.913962313307;4744.264638980963;4958.082754690394;5454.563634909197;5608.69152196625;5652.943470565324;1125.5947858770835;1133.6315697398213;1139.601139601133;1150.7986102432042;1157.1726215755775;25413.364665883502;26462.338441539116;27222.727772722414;27273.04544340978;27464.725352746616;872.1553913472959;993.7714325705128;1096.331237944853;1103.8840921703106;1118.340179294204;11416.801235870114;12250.326232057145;12718.982481401466;12767.489300428064;12840.229586224856;299674;313010;313568;314589;315703.1074223117;42658.933526662084;50694;51880.22179667308;55006.17490737642;55239.17141242885;1415.3347926474542;1457.3733294683389;1459.5986103821533;1459.8759105476154;1466.149633212734;23128.76871231266;24145.879270603535;27862.86068569725;27931.743071562236;28796.424071518035;43862;44051;44164.337534937644;44168;44180;300576;302468;304798;305131;305193;29946.70053299439;30542.541861872534;30575.388492229587;30669.8466507666;30743.077707668806;31430.528542072345;32070.518942215418;34250.82874585711;41849.581504185786;41976.58023419727;27503.724962750115;28765.71234287714;29546.409071818012;29700.851495742383;29871.551926720684;28468.572971405858;28677.42645147127;28859.42281154407;29556.852215738785;29651;32942.50586241256;34345.141371465914;34399.31201375908;34582.48126278057;34591;28636.85681571579;29007.85496072506;29173.270668233465;29203.41593168082;29804.254893627833;22608;22877.88561057184;23135.421614459774;23215.651765223196;23393.649095263925 02:32:25 INFO - PROCESS | 1640 | |2;jquery.html;383.47499505831183;427.11373899159236;429.7254102911993;435.0732938840272;435.08414786873624;15516.534503964871;20422.5527941316;22120.446988825286;22188.88905555472;22310.55378892421;20644.587108257827;21294.680579791315;21793.128274868985;21898.90505474728;22249.1100355986;4704.764761761899;6202.751889924409;6418.19772528434;6497.415232629062;6500.869982600345;6454.838629034288;6552.574082684634;6583.93416065838;6613.371729685672;6623.801285961405;6290.402411770874;6386.1697979262635;6463.482921366303;6473.126127972734;6609.074729537869;6396.168498095218;6402.647854368008;6443.355664433566;6470.967645161792;6473.287938326827;6072.149899014142;6289.0880822280515;6318.652474113922;6331.905021424683;6332.05019247112;24730.134445294327;24982.875085624637;25370.74629253721;25507.23478295654;25839.870800646066;3304.8182349970743;3309.8841540545964;3345.4312766829453;3363.7140843028374;3366.208940898887;14521.927390363086;15562.37750489979;15930.522084337488;16162.595935101599;17233.655326893393;2082.37528741377;2095.8742475451522;2105.147415296807;2133.28534940795;2145.881976491308;162329;168067;168174;171846.14076929662;171954;14903.031302965273;19553.18712851009;20453.48866278355;20662.863542505005;20670.79329206719;29143.271418214714;29572.40855182884;29772;29806.701932980828;29808.254793629898;9930.65242716516;10327.896721032694;10438.53026613789;10445.634402795866;10574.149523833452;7083;8526.531040792692;8599.957000215085;8737.51943643093;8742.650293988168;9298.767530811783;9397.577109029973;9468.053194680615;9508.809823803485;9526.285528585384;3525.7179425645886;3578.3380074686265;3762.529683789526;3846.7114966377635;3852.4221366795205;878.7451639024612;880.2078129683337;901.900790912999;915.3501014279802;931.82295363882;20305.49236269105;20467.07898144587;20744.48138796542;20834.47913802167;20862;904.5612877754284;929.330881765128;934.4813628436283;944.7496413450441;954.0411886054471;9959;10737.691396349666;10751.978562036595;10780.568777248978;10805.351678899291;726978;731571;754338;770283;772149;117830;135570;146165;160205.19897400672;166289.16855415647;1161.5702190189684;1162.3142346015723;1182.5033485935928;1189.048760991221;1191.9046476281876;17381.56546086358;18246.45260642183;18289.908550457163;18291.26834926588;18317.816821832144;40629.48609280553;51510;51534.48465515297;65623.67188164029;69663.65168174126;66136;89505;89851.05819180008;90240;90485.09514904767;11056.613018544313;11212.327260364404;11352.524171857605;11624.244424112405;11651.300921944372;12648.494060237488;12852.357382131044;12910;12929.159604625669;13042.347882606022;10625.25623206368;10782.460876956278;11066.39134847591;11130.220884538005;11208.943955280172;8150.2664760171865;9998.500074996367;10179.38923664553;10567.418791966516;10639.14886809069;10112.24158188124;10950.288231264942;11255.818139095278;11317.377544235145;11574.247673901169;11536.826947595624;11565.768684626091;11612.825807612724;11660.18378713482;11827.881721182677;10934.343939363664;10973.890261097606;11117.444127793417;11332.716682083013;11363 02:32:25 INFO - PROCESS | 1640 | |3;mootools.html;1516.9924150379247;1535.378171840405;1569.1683407793873;1574.3781206423464;1587.309520358644;10405.947970260147;10928.50821713023;10935.945320273408;10941.890581094187;10949.616763413273;10566.101881340077;10964.671059868197;11081.501332440039;11109.111271098312;11109.666709998693;2407.1935901473025;2445.99714117212;2448;2452;2453.362125847275;2089.5999680214295;2209.558088382322;2274.5337205872756;2328.1269523928527;2428.805695544352;2298.816094712419;2340.9531809363802;2361.0791791201445;2436.5370579589908;2447.265820253917;2313.4679023824447;2318.107528601484;2374.9762502375015;2387.8567285962813;2390.067873529325;2370.8340416170877;2398.8800559971933;2400.8799560022103;2402.3513651314224;2408.3256688127353;10778.946105269502;11017.889821101846;11018.669439916815;11035.613753518668;11048.337099774039;5761.078227483583;5931.792387266425;5936.347001829795;5962.731677074542;5977.491913187384;3480.6867381935494;3625.6374362563797;3626.9455958160647;3658.8902332930056;3716.2753263113705;1177.8527684039495;1186.9406529673481;1210.6549633354452;1215.7082300247964;1229.4221715793562;13662.385192666354;14120.505782297672;14415.711685766331;15344.309506072143;15512.922435387864;4620.353150558924;4808.374911261513;4810.6632535722865;4813.470518242991;4828.61371090312;11559.595414160463;11559.803637308885;11563.710907227387;11590.188686791844;11599.536018559333;4942.085714142895;5104.234364845229;5200.245964335226;5689.971550142222;5792.594518383757;5212.530872221442;5312.5484333832055;5361.678299302054;5393.946060539422;5402.162664786964;3476.687098161178;3906.0625449891822;3915.6280153385383;3961.207758448322;3992.5208974923194;1036.6993571864075;1037.4242295525905;1039.3763741754888;1045.926785125034;1048.910842578374;837.225566351135;869.5826003518347;874.8469017921839;892.205936716323;925.5418567808978;6903.75836845708;6965.094537710166;7023.473239507058;7038.260982596856;7082.822929426806;965.879579687559;1010.3786171504619;1030.6189705794272;1040.2770074798093;1078.6440474643348;4121.97939010309;4219.789010549522;4221.197972385298;4235.8093885775215;4371.322445021026;13079.673008174872;13677;13689.520866769615;13707.246101464516;13751.243681597409;13126.803097953538;13322.400491977884;13575.52485662997;13592.728145437233;13622.250776207205;10046.296759226783;10050.547725352377;10210.540525676213;10240.897591024144;10274.794504109876;6530.314316996647;6591.242007169237;6593.604383736991;6673.9332606673315;6686.9331306688255;11963.461644225858;12121.212121212093;12200.51197952072;12290.832370924747;12292.938535307267;9581.377210481296;11949.761004779682;12335.198212116184;12542;12726.68183295425;9223.077692230723;9392.154706076486;9394.67118650844;9396.295277854326;9403.717888463358;8820.91179088201;10032.297739158188;10177.796444071226;10256.743581410525;10268.178545716473;8207.384446166681;8252.092269850431;8711.607977640895;8751.781205469913;8804.163604457566;8583.742487725378;8618.956905215433;8641.56792160402;8709.869351959598;8877.556122193993;7841.294283514511;7980.08229053643;7990.081140668665;7992.560409177551;8004.679812807422;5767.798127065533;5775.133729940542;5838.2702162229725;5987.760489580368;5998.670073146019;5910.852228694317;6004.339522652417;6032.849178770566;6079.878402431838;6277.058441233851 02:32:25 INFO - PROCESS | 1640 | |4;prototype.html;326.4059411870394;374.810720586104;383.3961510620773;386.1369838411151;392.5838611072262;4596.563326483983;4632.86101416958;4977.55202031817;5240.659357141783;5251.5798736101115;4844.636652251086;5150.562202212807;5158.916627508218;5168.173092305232;5181.507756763111;3406.403879321121;3896.7530390275124;3991.44119823225;4274.422952901349;4288.313869780835;4142.875713728593;4250.744955302692;4320.956790432103;4321.956780432187;4332.696711230215;3737.635762946532;3939.2909276330247;4110.77390743509;4244.943640578794;4403.581659742335;4064.4716186895516;4221.079408868957;4229.323308270662;4311.137772445492;4386.2104821132025;3500.6499350065046;3794.31702293587;3937.8621748238675;3959.742616729903;3974.284628766821;4184.497860256758;5046.687861156126;5057.4689657585795;5059.623425736748;5076.695398276115;4248.259872382296;4550.453945526558;4876.440857422541;4969.608509617313;4980.377452818398;5171.65536960393;5245.606579506552;5511.807086751985;5530.391656917735;5550.417206193332;4036.5156181258435;4308.526062133163;4390.209762242795;4442.578374920027;4449.421575195204;4513.887152821206;4903.681260718042;5584.357798853184;5907.5864689471755;5923.17075609415;3936.4685767421606;4018.9799051005143;4258.211551148525;4437.8224871004795;4541.591256786905;3976.4234186042863;4576.83981060668;4589.449266088091;4637.791299391535;4651.906961860744;4438.294024489587;4500.774961251925;4791.95208047922;4951.474747878536;4968.627352948543;4949.4409261083365;5505.3393592768325;5540.487215220881;5564.415736347706;5572.275604171432;1436.3321055709146;1513.6140284227672;1565.6555557777272;1585.5322679809483;1596.361455417842;981.7349315684725;1149.8562679665042;1170.4264910193945;1182.6694968160818;1189.8155785853035;4671.416072990876;4774.355462012584;4863.465018847924;5147.356580427447;5214.869628259248;5691.044723916921;5724.771009159587;5749.016589276693;5770.019096753562;5772.740226689809;4671.789769460314;4776.1850496811685;4970.502949705001;5119.206522988943;5151.407588127413;2850.8717107730204;2954.4386566552284;2956.822590644568;2969.5248760198697;2976.3154474470994;5038.521340472649;5273.129933560919;5288.286081378965;5359.017163649474;5497.670139791545;5309.946900530946;5354.437784032699;5369.785208591613;5475.917861232085;5623.8875222496135;5180.948190518197;5189.40321862983;5269.341332333458;5460.180972854104;5667.4615911488345;5194.922076168936;5269.841904742864;5663.9804835128425;5724;5756.251687280626;5158.329417175743;5220.686758794484;5642.492175704207;5662.414523933306;5679.943200567942;5526.447355264441;5823.010088284917;5992.0525829104845;5998.970005150121;6003.459688628044;4231.365295205744;4600.653666629976;4842.273658951045;5023.648344615988;5041.07617581809;5107.770150343169;5569.944300556943;5730.942690573041;5768.3078030634915;5805.448482394166;5580.837725104343;5584.916226256691;5586.804461843816;5593.161025846156;5649.887002259849;4735.763211839463;4877.6829506081995;5349.806579302467;5615;5633.746481408264;4955.042758110454;5253.658512196695;5363.701323685983;5465.966388654335;5502.58730595215;5191.703268424502;5204.271402003798;5524.33707955048;5594.957840388311;5608.093248295599;4663.393758811333;5119.257707632447;5381.811636592701;5481.314835645544;5500.834974950758 02:32:25 INFO - PROCESS | 1640 | |5;yui.html;719.9172095209052;738.0368618952268;773.7137259214092;822.1737154160069;832.5961908474388;15177.924110379447;16058.59853503663;16058.678826423464;16085.678286434279;16094.91952540237;14546.418143274283;14569.12585244886;14669.486567970123;14680.926595367007;15174.696506069871;905.7961958559338;919.2783664823115;928.1090153452693;930.6975233049252;938.6667732954805;957.9941061884992;960.6685693435753;960.98615960162;962.1052421248237;962.870012548304;1008.425197637347;1010.2675560218819;1011.1304278320638;1011.2011510906411;1011.3173607814732;1003.4581326083905;1004.1565085328286;1005;1006.1296978113916;1006.5068116622839;749.2919191364163;749.5090715581302;749.7713197474768;749.8013026547969;750.6809605917517;12635.810462843065;15404.691906161926;15532.922335388364;16055.357785688559;16090.517284481368;13660.043796934218;13998.860011399858;14128.364223609962;14240.287985600678;14250.2162381069;11641.941790290994;11706.707332316675;11732.941335293353;11733.471993760217;12236.510539578407;3636.799976001319;3680;3727.329080765461;3757.4927384802977;3862.517185351831;114351.42824285825;117734;117780;118164.40917795357;120889.18666220014;10879.12966962641;11007.724806879893;11031.117510599133;11246.550137994553;11313.264637798517;22599;22984.885075574515;22991.425214369774;22998.080076797076;23007.88496057509;7623.199564045806;7692.38460923136;7765.689372425153;7836.725714600076;7924.445288829842;4231.724494925643;4291.849785257501;4358.782060896942;4520.819167233274;4584.220682483919;3434.261633748756;3525.2244506208594;3528.1356067763563;3537.593176784703;3538.929221415557;565.8528782516576;580.7299605683378;608.3855306140741;613.4724137241968;613.622622087422;517.5161224255334;628.8239292997968;673.1988933169513;675.8377989282537;717.8815495443298;9977.852546957047;10069.949650251701;10134.290599657954;10170.491475426346;10529.262951593315;598.937111603284;704.8237940514871;712.3624356201171;712.3944647049966;719.133444199738;4294.849680261176;4677.415323084614;4954.801807927714;4955.306257124005;4991.750412479361;101117.4832377515;103209.37290117072;105272;107345.92654073515;107567.92432075736;87990;90099.64850527247;90746;92412.15175696583;93636.06363936274;79750.60124699456;80017;82954;83086.58456707677;83914.5804270987;9615.759606009906;9669.419834809934;9710.271729620166;9719.85420218683;9721.951390243003;47762.522374777196;51419.45741627608;51428.48571514237;52970.2054469191;54068.45931540634;62549.68725156345;69875;70094;70627.23431914243;72055.63972180105;10543.26197166191;11041.889581104408;11080.33517988891;11254.268472549258;11595.710107247387;12108.273503589813;12283.754324913272;12422.130450868352;12675.049371297006;13300;13711.931440342734;14052.929735351257;14146.78779818324;14200.005999580344;14546;13477.932610336886;13747.931260343634;14149.929250353682;14176.787348189579;14304.785428218793;11814.822777658514;13047.804282935575;13056.804147937599;13256.53602123921;14284.857151428352;9294.349395542247;9618.663346783109;9846.310758246855;10261.846072308772;10269.229807764597;10772.299800513256;10942.234043617189;11095.72260693489;11202.159838012345;11259 02:32:25 INFO - PROCESS | 1640 | __end_tp_report 02:32:25 INFO - PROCESS | 1640 | __start_cc_report 02:32:25 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1565 02:32:25 INFO - PROCESS | 1640 | __end_cc_report 02:32:25 INFO - PROCESS | 1640 | __startTimestamp1491557545939__endTimestamp 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | ------- Summary: start ------- 02:32:25 INFO - PROCESS | 1640 | Number of tests: 6 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5951.26 Median:6153.38 stddev:654.44 (10.6%) stddev-sans-first:625.19 02:32:25 INFO - PROCESS | 1640 | Values: 3403.6 3419.8 3423.4 3435.0 3435.2 6004.1 6050.4 6061.2 6074.9 6105.5 6052.8 6107.3 6126.1 6135.8 6138.7 5430.8 5465.6 5494.2 5497.0 5574.5 5344.6 5347.4 5559.3 5574.3 5597.2 5383.5 5431.4 5438.9 5445.6 5453.0 5435.2 5437.5 5448.3 5449.0 5450.6 5246.0 5388.3 5392.3 5396.5 5418.9 6033.4 6039.0 6048.0 6048.5 6048.5 6249.3 6252.1 6275.5 6281.0 6304.3 5345.8 6176.4 6219.0 6238.8 6345.7 6066.5 6129.8 6130.2 6153.0 6153.4 6531.6 6596.7 6603.3 6612.1 6624.0 5148.9 5190.3 6217.5 6254.7 6269.5 6421.2 6461.9 6477.5 6477.8 6483.4 6232.4 6377.6 6377.8 6384.9 6393.7 6153.8 6211.6 6272.3 6356.8 6402.2 5146.3 5184.3 5191.7 5196.1 5226.7 4567.8 4573.7 4596.4 4613.2 4634.7 5827.1 5919.4 6118.4 6119.6 6130.8 6266.8 6394.2 6436.8 6484.0 6490.0 6074.5 6116.6 6130.6 6139.5 6139.5 5676.2 5711.3 5719.2 5726.1 5736.7 6339.6 6344.2 6458.1 6625.4 6686.3 6493.1 6518.7 6529.4 6529.7 6535.7 6686.0 6689.0 6724.2 6744.1 6774.2 6253.2 6265.8 6290.4 6326.2 6410.9 6268.7 6406.5 6412.6 6425.2 6426.7 6310.9 6311.7 6315.2 6315.3 6315.8 5635.9 5991.2 6166.7 6223.5 6248.3 6200.9 6275.6 6285.6 6287.3 6293.7 6264.0 6275.5 6300.0 6308.8 6315.1 6066.2 6107.3 6118.0 6189.4 6238.3 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:47958.51 Median:300125.00 stddev:87040.20 (29.0%) stddev-sans-first:87212.18 02:32:25 INFO - PROCESS | 1640 | Values: 741.3 782.7 803.4 864.0 875.8 28118.2 40164.8 40730.2 40801.4 40935.0 41677.8 41923.2 41954.4 42053.9 42118.0 7430.4 9319.4 9487.4 9499.1 9507.3 7501.0 11033.3 11310.2 11413.3 11435.3 11279.2 11291.3 11309.7 11323.3 11333.5 11259.7 11286.0 11287.3 11292.9 11315.9 11030.4 11085.9 11095.5 11096.0 11120.9 39188.6 39470.6 39476.0 39507.6 39516.8 3769.6 3803.5 3820.4 3842.3 3863.2 27502.2 29306.9 29577.6 29668.7 29798.3 2937.3 3038.7 3046.9 3125.9 3155.7 372052.0 372863.3 373324.0 374261.0 375504.0 53018.0 53023.0 53043.9 53093.9 53106.0 54729.9 54849.5 55108.2 55191.0 55193.7 14839.8 14967.6 14967.9 14981.9 14989.9 10927.6 11176.8 12818.4 12840.2 12869.9 10928.2 12244.1 12530.7 12553.4 12776.9 4744.3 4958.1 5454.6 5608.7 5652.9 1125.6 1133.6 1139.6 1150.8 1157.2 25413.4 26462.3 27222.7 27273.0 27464.7 872.2 993.8 1096.3 1103.9 1118.3 11416.8 12250.3 12719.0 12767.5 12840.2 299674.0 313010.0 313568.0 314589.0 315703.1 42658.9 50694.0 51880.2 55006.2 55239.2 1415.3 1457.4 1459.6 1459.9 1466.1 23128.8 24145.9 27862.9 27931.7 28796.4 43862.0 44051.0 44164.3 44168.0 44180.0 300576.0 302468.0 304798.0 305131.0 305193.0 29946.7 30542.5 30575.4 30669.8 30743.1 31430.5 32070.5 34250.8 41849.6 41976.6 27503.7 28765.7 29546.4 29700.9 29871.6 28468.6 28677.4 28859.4 29556.9 29651.0 32942.5 34345.1 34399.3 34582.5 34591.0 28636.9 29007.9 29173.3 29203.4 29804.3 22608.0 22877.9 23135.4 23215.7 23393.6 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:42742.19 Median:23520.34 stddev:125555.24 (533.8%) stddev-sans-first:125867.16 02:32:25 INFO - PROCESS | 1640 | Values: 383.5 427.1 429.7 435.1 435.1 15516.5 20422.6 22120.4 22188.9 22310.6 20644.6 21294.7 21793.1 21898.9 22249.1 4704.8 6202.8 6418.2 6497.4 6500.9 6454.8 6552.6 6583.9 6613.4 6623.8 6290.4 6386.2 6463.5 6473.1 6609.1 6396.2 6402.6 6443.4 6471.0 6473.3 6072.1 6289.1 6318.7 6331.9 6332.1 24730.1 24982.9 25370.7 25507.2 25839.9 3304.8 3309.9 3345.4 3363.7 3366.2 14521.9 15562.4 15930.5 16162.6 17233.7 2082.4 2095.9 2105.1 2133.3 2145.9 162329.0 168067.0 168174.0 171846.1 171954.0 14903.0 19553.2 20453.5 20662.9 20670.8 29143.3 29572.4 29772.0 29806.7 29808.3 9930.7 10327.9 10438.5 10445.6 10574.1 7083.0 8526.5 8600.0 8737.5 8742.7 9298.8 9397.6 9468.1 9508.8 9526.3 3525.7 3578.3 3762.5 3846.7 3852.4 878.7 880.2 901.9 915.4 931.8 20305.5 20467.1 20744.5 20834.5 20862.0 904.6 929.3 934.5 944.7 954.0 9959.0 10737.7 10752.0 10780.6 10805.4 726978.0 731571.0 754338.0 770283.0 772149.0 117830.0 135570.0 146165.0 160205.2 166289.2 1161.6 1162.3 1182.5 1189.0 1191.9 17381.6 18246.5 18289.9 18291.3 18317.8 40629.5 51510.0 51534.5 65623.7 69663.7 66136.0 89505.0 89851.1 90240.0 90485.1 11056.6 11212.3 11352.5 11624.2 11651.3 12648.5 12852.4 12910.0 12929.2 13042.3 10625.3 10782.5 11066.4 11130.2 11208.9 8150.3 9998.5 10179.4 10567.4 10639.1 10112.2 10950.3 11255.8 11317.4 11574.2 11536.8 11565.8 11612.8 11660.2 11827.9 10934.3 10973.9 11117.4 11332.7 11363.0 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6688.92 Median:2432.67 stddev:4133.23 (169.9%) stddev-sans-first:4126.56 02:32:25 INFO - PROCESS | 1640 | Values: 1517.0 1535.4 1569.2 1574.4 1587.3 10405.9 10928.5 10935.9 10941.9 10949.6 10566.1 10964.7 11081.5 11109.1 11109.7 2407.2 2446.0 2448.0 2452.0 2453.4 2089.6 2209.6 2274.5 2328.1 2428.8 2298.8 2341.0 2361.1 2436.5 2447.3 2313.5 2318.1 2375.0 2387.9 2390.1 2370.8 2398.9 2400.9 2402.4 2408.3 10778.9 11017.9 11018.7 11035.6 11048.3 5761.1 5931.8 5936.3 5962.7 5977.5 3480.7 3625.6 3626.9 3658.9 3716.3 1177.9 1186.9 1210.7 1215.7 1229.4 13662.4 14120.5 14415.7 15344.3 15512.9 4620.4 4808.4 4810.7 4813.5 4828.6 11559.6 11559.8 11563.7 11590.2 11599.5 4942.1 5104.2 5200.2 5690.0 5792.6 5212.5 5312.5 5361.7 5393.9 5402.2 3476.7 3906.1 3915.6 3961.2 3992.5 1036.7 1037.4 1039.4 1045.9 1048.9 837.2 869.6 874.8 892.2 925.5 6903.8 6965.1 7023.5 7038.3 7082.8 965.9 1010.4 1030.6 1040.3 1078.6 4122.0 4219.8 4221.2 4235.8 4371.3 13079.7 13677.0 13689.5 13707.2 13751.2 13126.8 13322.4 13575.5 13592.7 13622.3 10046.3 10050.5 10210.5 10240.9 10274.8 6530.3 6591.2 6593.6 6673.9 6686.9 11963.5 12121.2 12200.5 12290.8 12292.9 9581.4 11949.8 12335.2 12542.0 12726.7 9223.1 9392.2 9394.7 9396.3 9403.7 8820.9 10032.3 10177.8 10256.7 10268.2 8207.4 8252.1 8711.6 8751.8 8804.2 8583.7 8619.0 8641.6 8709.9 8877.6 7841.3 7980.1 7990.1 7992.6 8004.7 5767.8 5775.1 5838.3 5987.8 5998.7 5910.9 6004.3 6032.8 6079.9 6277.1 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4603.33 Median:5031.08 stddev:1277.74 (25.4%) stddev-sans-first:1240.34 02:32:25 INFO - PROCESS | 1640 | Values: 326.4 374.8 383.4 386.1 392.6 4596.6 4632.9 4977.6 5240.7 5251.6 4844.6 5150.6 5158.9 5168.2 5181.5 3406.4 3896.8 3991.4 4274.4 4288.3 4142.9 4250.7 4321.0 4322.0 4332.7 3737.6 3939.3 4110.8 4244.9 4403.6 4064.5 4221.1 4229.3 4311.1 4386.2 3500.6 3794.3 3937.9 3959.7 3974.3 4184.5 5046.7 5057.5 5059.6 5076.7 4248.3 4550.5 4876.4 4969.6 4980.4 5171.7 5245.6 5511.8 5530.4 5550.4 4036.5 4308.5 4390.2 4442.6 4449.4 4513.9 4903.7 5584.4 5907.6 5923.2 3936.5 4019.0 4258.2 4437.8 4541.6 3976.4 4576.8 4589.4 4637.8 4651.9 4438.3 4500.8 4792.0 4951.5 4968.6 4949.4 5505.3 5540.5 5564.4 5572.3 1436.3 1513.6 1565.7 1585.5 1596.4 981.7 1149.9 1170.4 1182.7 1189.8 4671.4 4774.4 4863.5 5147.4 5214.9 5691.0 5724.8 5749.0 5770.0 5772.7 4671.8 4776.2 4970.5 5119.2 5151.4 2850.9 2954.4 2956.8 2969.5 2976.3 5038.5 5273.1 5288.3 5359.0 5497.7 5309.9 5354.4 5369.8 5475.9 5623.9 5180.9 5189.4 5269.3 5460.2 5667.5 5194.9 5269.8 5664.0 5724.0 5756.3 5158.3 5220.7 5642.5 5662.4 5679.9 5526.4 5823.0 5992.1 5999.0 6003.5 4231.4 4600.7 4842.3 5023.6 5041.1 5107.8 5569.9 5730.9 5768.3 5805.4 5580.8 5584.9 5586.8 5593.2 5649.9 4735.8 4877.7 5349.8 5615.0 5633.7 4955.0 5253.7 5363.7 5466.0 5502.6 5191.7 5204.3 5524.3 5595.0 5608.1 4663.4 5119.3 5381.8 5481.3 5500.8 02:32:25 INFO - PROCESS | 1640 | 02:32:25 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:21083.28 Median:13221.07 stddev:31002.07 (234.5%) stddev-sans-first:31051.33 02:32:25 INFO - PROCESS | 1640 | Values: 719.9 738.0 773.7 822.2 832.6 15177.9 16058.6 16058.7 16085.7 16094.9 14546.4 14569.1 14669.5 14680.9 15174.7 905.8 919.3 928.1 930.7 938.7 958.0 960.7 961.0 962.1 962.9 1008.4 1010.3 1011.1 1011.2 1011.3 1003.5 1004.2 1005.0 1006.1 1006.5 749.3 749.5 749.8 749.8 750.7 12635.8 15404.7 15532.9 16055.4 16090.5 13660.0 13998.9 14128.4 14240.3 14250.2 11641.9 11706.7 11732.9 11733.5 12236.5 3636.8 3680.0 3727.3 3757.5 3862.5 114351.4 117734.0 117780.0 118164.4 120889.2 10879.1 11007.7 11031.1 11246.6 11313.3 22599.0 22984.9 22991.4 22998.1 23007.9 7623.2 7692.4 7765.7 7836.7 7924.4 4231.7 4291.8 4358.8 4520.8 4584.2 3434.3 3525.2 3528.1 3537.6 3538.9 565.9 580.7 608.4 613.5 613.6 517.5 628.8 673.2 675.8 717.9 9977.9 10069.9 10134.3 10170.5 10529.3 598.9 704.8 712.4 712.4 719.1 4294.8 4677.4 4954.8 4955.3 4991.8 101117.5 103209.4 105272.0 107345.9 107567.9 87990.0 90099.6 90746.0 92412.2 93636.1 79750.6 80017.0 82954.0 83086.6 83914.6 9615.8 9669.4 9710.3 9719.9 9722.0 47762.5 51419.5 51428.5 52970.2 54068.5 62549.7 69875.0 70094.0 70627.2 72055.6 10543.3 11041.9 11080.3 11254.3 11595.7 12108.3 12283.8 12422.1 12675.0 13300.0 13711.9 14052.9 14146.8 14200.0 14546.0 13477.9 13747.9 14149.9 14176.8 14304.8 11814.8 13047.8 13056.8 13256.5 14284.9 9294.3 9618.7 9846.3 10261.8 10269.2 10772.3 10942.2 11095.7 11202.2 11259.0 02:32:25 INFO - PROCESS | 1640 | -------- Summary: end -------- 02:32:25 INFO - PROCESS | 1640 | 02:32:26 INFO - TEST-INFO | 1640: exit 0 02:32:26 INFO - TEST-OK | dromaeo_css | took 1120684ms 02:32:26 INFO - TEST-START | kraken 02:32:26 INFO - operating with platform_type : mac_ 02:32:26 INFO - Initialising browser for kraken test... 02:32:26 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpl_GxPX/profile http://localhost:49241/getInfo.html) 02:32:28 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 02:32:28 INFO - PROCESS | 1648 | colorDepth:24 02:32:28 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 02:32:28 INFO - PROCESS | 1648 | __metrics 02:32:28 INFO - PROCESS | 1648 | 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" 02:32:28 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:32:29 INFO - TEST-INFO | 1648: exit 0 02:32:29 INFO - Browser initialized. 02:32:29 INFO - Running cycle 1/1 for kraken test... 02:32:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpl_GxPX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:41 INFO - PROCESS | 1650 | RSS: Main: 210804736 02:32:41 INFO - PROCESS | 1650 | 02:32:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:32:45 INFO - PROCESS | 1650 | RSS: Main: 264208384 02:32:45 INFO - PROCESS | 1650 | 02:32:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:32:48 INFO - PROCESS | 1650 | RSS: Main: 277463040 02:32:48 INFO - PROCESS | 1650 | 02:32:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:32:52 INFO - PROCESS | 1650 | RSS: Main: 374128640 02:32:52 INFO - PROCESS | 1650 | 02:32:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:32:55 INFO - PROCESS | 1650 | RSS: Main: 277774336 02:32:55 INFO - PROCESS | 1650 | 02:32:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:32:57 INFO - PROCESS | 1650 | RSS: Main: 280199168 02:32:57 INFO - PROCESS | 1650 | 02:33:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:33:01 INFO - PROCESS | 1650 | RSS: Main: 321171456 02:33:01 INFO - PROCESS | 1650 | 02:33:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:33:05 INFO - PROCESS | 1650 | RSS: Main: 322568192 02:33:05 INFO - PROCESS | 1650 | 02:33:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:33:08 INFO - PROCESS | 1650 | RSS: Main: 320016384 02:33:08 INFO - PROCESS | 1650 | 02:33:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:33:11 INFO - PROCESS | 1650 | RSS: Main: 295915520 02:33:11 INFO - PROCESS | 1650 | 02:33:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:33:13 INFO - PROCESS | 1650 | RSS: Main: 299974656 02:33:13 INFO - PROCESS | 1650 | 02:33:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:33:16 INFO - PROCESS | 1650 | RSS: Main: 303239168 02:33:16 INFO - PROCESS | 1650 | 02:33:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:33:20 INFO - PROCESS | 1650 | RSS: Main: 318230528 02:33:20 INFO - PROCESS | 1650 | 02:33:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:33:23 INFO - PROCESS | 1650 | RSS: Main: 312016896 02:33:23 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:33:25 INFO - PROCESS | 1650 | RSS: Main: 356458496 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | __start_tp_report 02:33:25 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 02:33:25 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 02:33:25 INFO - PROCESS | 1650 | |i|pagename|runs| 02:33:25 INFO - PROCESS | 1650 | |0;ai-astar;107;116;125;170;154;116;116;117;120;167 02:33:25 INFO - PROCESS | 1650 | |1;audio-beat-detection;146;111;107;144;109;109;109;109;130;109 02:33:25 INFO - PROCESS | 1650 | |2;audio-dft;187;185;185;187;185;185;184;187;184;184 02:33:25 INFO - PROCESS | 1650 | |3;audio-fft;82;62;60;92;61;62;59;60;77;60 02:33:25 INFO - PROCESS | 1650 | |4;audio-oscillator;85;70;86;69;69;68;83;69;68;83 02:33:25 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;89;89;89;89;88;89;91;134;105;88 02:33:25 INFO - PROCESS | 1650 | |6;imaging-darkroom;97;95;96;96;96;96;96;99;95;95 02:33:25 INFO - PROCESS | 1650 | |7;imaging-desaturate;86;85;83;86;83;83;83;83;82;83 02:33:25 INFO - PROCESS | 1650 | |8;json-parse-financial;61;59;57;59;88;91;92;85;63;64 02:33:25 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;57;65;69;63;60;57;58;61;80;81 02:33:25 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;90;92;84;86;84;82;81;83;77;86 02:33:25 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;138;141;147;140;134;131;140;139;179;134 02:33:25 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;167;166;166;182;217;197;166;163;162;165 02:33:25 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;64;69;78;80;80;64;64;62;63;65 02:33:25 INFO - PROCESS | 1650 | __end_tp_report 02:33:25 INFO - PROCESS | 1650 | __start_cc_report 02:33:25 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2198 02:33:25 INFO - PROCESS | 1650 | __end_cc_report 02:33:25 INFO - PROCESS | 1650 | __startTimestamp1491557605689__endTimestamp 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | ------- Summary: start ------- 02:33:25 INFO - PROCESS | 1650 | Number of tests: 14 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:130.80 Median:122.50 stddev:23.45 (19.1%) stddev-sans-first:23.24 02:33:25 INFO - PROCESS | 1650 | Values: 107.0 116.0 125.0 170.0 154.0 116.0 116.0 117.0 120.0 167.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:110.00 stddev:15.56 (14.1%) stddev-sans-first:12.87 02:33:25 INFO - PROCESS | 1650 | Values: 146.0 111.0 107.0 144.0 109.0 109.0 109.0 109.0 130.0 109.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.00 stddev:1.25 (0.7%) stddev-sans-first:1.17 02:33:25 INFO - PROCESS | 1650 | Values: 187.0 185.0 185.0 187.0 185.0 185.0 184.0 187.0 184.0 184.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.00 stddev:11.76 (19.0%) stddev-sans-first:11.24 02:33:25 INFO - PROCESS | 1650 | Values: 82.0 62.0 60.0 92.0 61.0 62.0 59.0 60.0 77.0 60.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:76.50 stddev:8.03 (10.5%) stddev-sans-first:7.66 02:33:25 INFO - PROCESS | 1650 | Values: 85.0 70.0 86.0 69.0 69.0 68.0 83.0 69.0 68.0 83.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:89.00 stddev:14.59 (16.4%) stddev-sans-first:15.30 02:33:25 INFO - PROCESS | 1650 | Values: 89.0 89.0 89.0 89.0 88.0 89.0 91.0 134.0 105.0 88.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.00 stddev:1.20 (1.2%) stddev-sans-first:1.22 02:33:25 INFO - PROCESS | 1650 | Values: 97.0 95.0 96.0 96.0 96.0 96.0 96.0 99.0 95.0 95.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:83.00 stddev:1.42 (1.7%) stddev-sans-first:1.24 02:33:25 INFO - PROCESS | 1650 | Values: 86.0 85.0 83.0 86.0 83.0 83.0 83.0 83.0 82.0 83.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:71.90 Median:74.50 stddev:14.96 (20.1%) stddev-sans-first:15.34 02:33:25 INFO - PROCESS | 1650 | Values: 61.0 59.0 57.0 59.0 88.0 91.0 92.0 85.0 63.0 64.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:64.00 stddev:8.94 (14.0%) stddev-sans-first:8.99 02:33:25 INFO - PROCESS | 1650 | Values: 57.0 65.0 69.0 63.0 60.0 57.0 58.0 61.0 80.0 81.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:4.33 (5.1%) stddev-sans-first:4.11 02:33:25 INFO - PROCESS | 1650 | Values: 90.0 92.0 84.0 86.0 84.0 82.0 81.0 83.0 77.0 86.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:140.00 stddev:13.65 (9.7%) stddev-sans-first:14.39 02:33:25 INFO - PROCESS | 1650 | Values: 138.0 141.0 147.0 140.0 134.0 131.0 140.0 139.0 179.0 134.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:166.50 stddev:18.31 (11.0%) stddev-sans-first:19.18 02:33:25 INFO - PROCESS | 1650 | Values: 167.0 166.0 166.0 182.0 217.0 197.0 166.0 163.0 162.0 165.0 02:33:25 INFO - PROCESS | 1650 | 02:33:25 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:67.00 stddev:7.45 (11.1%) stddev-sans-first:7.68 02:33:25 INFO - PROCESS | 1650 | Values: 64.0 69.0 78.0 80.0 80.0 64.0 64.0 62.0 63.0 65.0 02:33:25 INFO - PROCESS | 1650 | -------- Summary: end -------- 02:33:25 INFO - PROCESS | 1650 | 02:33:26 INFO - TEST-INFO | 1650: exit 0 02:33:26 INFO - TEST-OK | kraken | took 59937ms 02:33:26 INFO - SUITE-END | took 1180s 02:33:26 INFO - Completed test suite (00:19:40) 02:33:26 INFO - javascript benchmark 02:33:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7642.775603771587, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.625601183871, 3419.81191034493, 3423.383790917635, 3435.0210190095827, 3435.2099017226037, 6004.129401236818, 6050.394960503947, 6061.2120424344885, 6074.878502429949, 6105.450509454148, 6052.818415447544, 6107.328193898678, 6126.081087836816, 6135.81592552223, 6138.693065346726, 5430.782768689248, 5465.617406781527, 5494.230807686927, 5496.972515137419, 5574.498295153456, 5344.599155063366, 5347.41178470368, 5559.305086864142, 5574.331080270374, 5597.188407680904, 5383.515483606475, 5431.375391829931, 5438.91841622376, 5445.646033007862, 5453.045716999529, 5435.157550579666, 5437.456254374571, 5448.318960129984, 5449.04641687705, 5450.618456707993, 5245.973770131163, 5388.326459192601, 5392.299001129828, 5396.514313711785, 5418.945810541923, 6033.396660333975, 6039.003564411867, 6047.969760151171, 6048.455638992512, 6048.5463590230465, 6249.281332646713, 6252.06219067139, 6275.466585340251, 6281.0, 6304.306526282106, 5345.7594408251725, 6176.444120029174, 6219.0, 6238.750449981995, 6345.714442850082, 6066.4540191382985, 6129.7854575090105, 6130.2030736004035, 6153.0, 6153.384661533855, 6531.640759758259, 6596.736130554772, 6603.3396660334065, 6612.107365505595, 6623.966880165664, 5148.897022059537, 5190.2993095932325, 6217.471514921192, 6254.6872656366995, 6269.529785266123, 6421.229452465734, 6461.903071453838, 6477.5465717400275, 6477.805665830033, 6483.384078512534, 6232.407921247474, 6377.585456945284, 6377.776777812847, 6384.904226436607, 6393.744250230032, 6153.815385538441, 6211.596246243979, 6272.3100458949475, 6356.841078973062, 6402.167718196698, 5146.279520867081, 5184.326037615161, 5191.662541934762, 5196.064708352533, 5226.660267082703, 4567.840125595588, 4573.748443835621, 4596.35651008859, 4613.215753321876, 4634.745089020069, 5827.125931110282, 5919.378465261172, 6118.388161183846, 6119.571629985859, 6130.754769809247, 6266.811995640138, 6394.23269207698, 6436.774712885027, 6484.0, 6490.0, 6074.51403887688, 6116.571839971249, 6130.570860039755, 6139.508839292936, 6139.539534534928, 5676.205331253545, 5711.343195532484, 5719.170720245541, 5726.112452569859, 5736.741846616912, 6339.587926784744, 6344.175257216532, 6458.063580780667, 6625.370589793962, 6686.264510903699, 6493.123428337114, 6518.73925042993, 6529.444997175293, 6529.7388104475285, 6535.738570457129, 6686.030525573668, 6688.966555167193, 6724.226713927766, 6744.123263975652, 6774.153230846145, 6253.218347706536, 6265.780697675559, 6290.370962903673, 6326.240851097898, 6410.935890641033, 6268.749250029948, 6406.519511036784, 6412.647304398303, 6425.228972523327, 6426.710798014007, 6310.905336419865, 6311.684415779285, 6315.210598675166, 6315.27374351937, 6315.778947736807, 5635.887282254414, 5991.191189189403, 6166.722497487534, 6223.502119830312, 6248.312685604489, 6200.937990620217, 6275.623462592259, 6285.59143655661, 6287.30839607652, 6293.653849038347, 6263.96868015657, 6275.466585340115, 6300.0, 6308.842278943063, 6315.147455093504, 6066.18106555627, 6107.267127944675, 6117.96941015292, 6189.35011823752, 6238.313785483684], "value": 5909.473418604382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.2846603028077, 782.7299581644332, 803.3813963248301, 863.9827203455931, 875.7591662292873, 28118.156455306347, 40164.79917600412, 40730.18539629206, 40801.387979180334, 40935.0, 41677.791611041976, 41923.16153676924, 41954.37068443976, 42053.94865128373, 42118.0, 7430.405567554582, 9319.394239374453, 9487.383320084207, 9499.097585729345, 9507.286953478482, 7501.0248667673395, 11033.282836615634, 11310.208285420023, 11413.25813822103, 11435.256708313975, 11279.154063445234, 11291.322520648788, 11309.717257068598, 11323.32060076394, 11333.546658133664, 11259.718507037307, 11286.0, 11287.266327688674, 11292.887071129266, 11315.943420282929, 11030.448477576088, 11085.944570277177, 11095.50070246841, 11096.0, 11120.944395278053, 39188.608113919065, 39470.605293947265, 39476.0, 39507.60492395097, 39516.80241598774, 3769.5664998525244, 3803.5055442792536, 3820.3505404081375, 3842.269968705965, 3863.2466668999555, 27502.17493475199, 29306.853465732747, 29577.55633665497, 29668.70331296681, 29798.255043623867, 2937.3244153844735, 3038.6657467678538, 3046.923826904323, 3125.8749650013974, 3155.7159855612877, 372052.0, 372863.27136728825, 373324.0, 374261.0, 375504.0, 53018.0, 53023.0, 53043.93912121736, 53093.938121237356, 53106.0, 54729.90540189174, 54849.45150548523, 55108.17337739937, 55191.0, 55193.72403138039, 14839.777403338958, 14967.550973470814, 14967.925160374347, 14981.850181498046, 14989.925050374679, 10927.617533386452, 11176.776464470666, 12818.423170957329, 12840.165389249669, 12869.871301286867, 10928.235023548434, 12244.142909996215, 12530.749385012248, 12553.372331383394, 12776.913962313307, 4744.264638980963, 4958.082754690394, 5454.563634909197, 5608.69152196625, 5652.943470565324, 1125.5947858770835, 1133.6315697398213, 1139.601139601133, 1150.7986102432042, 1157.1726215755775, 25413.364665883502, 26462.338441539116, 27222.727772722414, 27273.04544340978, 27464.725352746616, 872.1553913472959, 993.7714325705128, 1096.331237944853, 1103.8840921703106, 1118.340179294204, 11416.801235870114, 12250.326232057145, 12718.982481401466, 12767.489300428064, 12840.229586224856, 299674.0, 313010.0, 313568.0, 314589.0, 315703.1074223117, 42658.933526662084, 50694.0, 51880.22179667308, 55006.17490737642, 55239.17141242885, 1415.3347926474542, 1457.3733294683389, 1459.5986103821533, 1459.8759105476154, 1466.149633212734, 23128.76871231266, 24145.879270603535, 27862.86068569725, 27931.743071562236, 28796.424071518035, 43862.0, 44051.0, 44164.337534937644, 44168.0, 44180.0, 300576.0, 302468.0, 304798.0, 305131.0, 305193.0, 29946.70053299439, 30542.541861872534, 30575.388492229587, 30669.8466507666, 30743.077707668806, 31430.528542072345, 32070.518942215418, 34250.82874585711, 41849.581504185786, 41976.58023419727, 27503.724962750115, 28765.71234287714, 29546.409071818012, 29700.851495742383, 29871.551926720684, 28468.572971405858, 28677.42645147127, 28859.42281154407, 29556.852215738785, 29651.0, 32942.50586241256, 34345.141371465914, 34399.31201375908, 34582.48126278057, 34591.0, 28636.85681571579, 29007.85496072506, 29173.270668233465, 29203.41593168082, 29804.254893627833, 22608.0, 22877.88561057184, 23135.421614459774, 23215.651765223196, 23393.649095263925], "value": 18148.132071255357, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.47499505831183, 427.11373899159236, 429.7254102911993, 435.0732938840272, 435.08414786873624, 15516.534503964871, 20422.5527941316, 22120.446988825286, 22188.88905555472, 22310.55378892421, 20644.587108257827, 21294.680579791315, 21793.128274868985, 21898.90505474728, 22249.1100355986, 4704.764761761899, 6202.751889924409, 6418.19772528434, 6497.415232629062, 6500.869982600345, 6454.838629034288, 6552.574082684634, 6583.93416065838, 6613.371729685672, 6623.801285961405, 6290.402411770874, 6386.1697979262635, 6463.482921366303, 6473.126127972734, 6609.074729537869, 6396.168498095218, 6402.647854368008, 6443.355664433566, 6470.967645161792, 6473.287938326827, 6072.149899014142, 6289.0880822280515, 6318.652474113922, 6331.905021424683, 6332.05019247112, 24730.134445294327, 24982.875085624637, 25370.74629253721, 25507.23478295654, 25839.870800646066, 3304.8182349970743, 3309.8841540545964, 3345.4312766829453, 3363.7140843028374, 3366.208940898887, 14521.927390363086, 15562.37750489979, 15930.522084337488, 16162.595935101599, 17233.655326893393, 2082.37528741377, 2095.8742475451522, 2105.147415296807, 2133.28534940795, 2145.881976491308, 162329.0, 168067.0, 168174.0, 171846.14076929662, 171954.0, 14903.031302965273, 19553.18712851009, 20453.48866278355, 20662.863542505005, 20670.79329206719, 29143.271418214714, 29572.40855182884, 29772.0, 29806.701932980828, 29808.254793629898, 9930.65242716516, 10327.896721032694, 10438.53026613789, 10445.634402795866, 10574.149523833452, 7083.0, 8526.531040792692, 8599.957000215085, 8737.51943643093, 8742.650293988168, 9298.767530811783, 9397.577109029973, 9468.053194680615, 9508.809823803485, 9526.285528585384, 3525.7179425645886, 3578.3380074686265, 3762.529683789526, 3846.7114966377635, 3852.4221366795205, 878.7451639024612, 880.2078129683337, 901.900790912999, 915.3501014279802, 931.82295363882, 20305.49236269105, 20467.07898144587, 20744.48138796542, 20834.47913802167, 20862.0, 904.5612877754284, 929.330881765128, 934.4813628436283, 944.7496413450441, 954.0411886054471, 9959.0, 10737.691396349666, 10751.978562036595, 10780.568777248978, 10805.351678899291, 726978.0, 731571.0, 754338.0, 770283.0, 772149.0, 117830.0, 135570.0, 146165.0, 160205.19897400672, 166289.16855415647, 1161.5702190189684, 1162.3142346015723, 1182.5033485935928, 1189.048760991221, 1191.9046476281876, 17381.56546086358, 18246.45260642183, 18289.908550457163, 18291.26834926588, 18317.816821832144, 40629.48609280553, 51510.0, 51534.48465515297, 65623.67188164029, 69663.65168174126, 66136.0, 89505.0, 89851.05819180008, 90240.0, 90485.09514904767, 11056.613018544313, 11212.327260364404, 11352.524171857605, 11624.244424112405, 11651.300921944372, 12648.494060237488, 12852.357382131044, 12910.0, 12929.159604625669, 13042.347882606022, 10625.25623206368, 10782.460876956278, 11066.39134847591, 11130.220884538005, 11208.943955280172, 8150.2664760171865, 9998.500074996367, 10179.38923664553, 10567.418791966516, 10639.14886809069, 10112.24158188124, 10950.288231264942, 11255.818139095278, 11317.377544235145, 11574.247673901169, 11536.826947595624, 11565.768684626091, 11612.825807612724, 11660.18378713482, 11827.881721182677, 10934.343939363664, 10973.890261097606, 11117.444127793417, 11332.716682083013, 11363.0], "value": 11271.223472365238, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.9924150379247, 1535.378171840405, 1569.1683407793873, 1574.3781206423464, 1587.309520358644, 10405.947970260147, 10928.50821713023, 10935.945320273408, 10941.890581094187, 10949.616763413273, 10566.101881340077, 10964.671059868197, 11081.501332440039, 11109.111271098312, 11109.666709998693, 2407.1935901473025, 2445.99714117212, 2448.0, 2452.0, 2453.362125847275, 2089.5999680214295, 2209.558088382322, 2274.5337205872756, 2328.1269523928527, 2428.805695544352, 2298.816094712419, 2340.9531809363802, 2361.0791791201445, 2436.5370579589908, 2447.265820253917, 2313.4679023824447, 2318.107528601484, 2374.9762502375015, 2387.8567285962813, 2390.067873529325, 2370.8340416170877, 2398.8800559971933, 2400.8799560022103, 2402.3513651314224, 2408.3256688127353, 10778.946105269502, 11017.889821101846, 11018.669439916815, 11035.613753518668, 11048.337099774039, 5761.078227483583, 5931.792387266425, 5936.347001829795, 5962.731677074542, 5977.491913187384, 3480.6867381935494, 3625.6374362563797, 3626.9455958160647, 3658.8902332930056, 3716.2753263113705, 1177.8527684039495, 1186.9406529673481, 1210.6549633354452, 1215.7082300247964, 1229.4221715793562, 13662.385192666354, 14120.505782297672, 14415.711685766331, 15344.309506072143, 15512.922435387864, 4620.353150558924, 4808.374911261513, 4810.6632535722865, 4813.470518242991, 4828.61371090312, 11559.595414160463, 11559.803637308885, 11563.710907227387, 11590.188686791844, 11599.536018559333, 4942.085714142895, 5104.234364845229, 5200.245964335226, 5689.971550142222, 5792.594518383757, 5212.530872221442, 5312.5484333832055, 5361.678299302054, 5393.946060539422, 5402.162664786964, 3476.687098161178, 3906.0625449891822, 3915.6280153385383, 3961.207758448322, 3992.5208974923194, 1036.6993571864075, 1037.4242295525905, 1039.3763741754888, 1045.926785125034, 1048.910842578374, 837.225566351135, 869.5826003518347, 874.8469017921839, 892.205936716323, 925.5418567808978, 6903.75836845708, 6965.094537710166, 7023.473239507058, 7038.260982596856, 7082.822929426806, 965.879579687559, 1010.3786171504619, 1030.6189705794272, 1040.2770074798093, 1078.6440474643348, 4121.97939010309, 4219.789010549522, 4221.197972385298, 4235.8093885775215, 4371.322445021026, 13079.673008174872, 13677.0, 13689.520866769615, 13707.246101464516, 13751.243681597409, 13126.803097953538, 13322.400491977884, 13575.52485662997, 13592.728145437233, 13622.250776207205, 10046.296759226783, 10050.547725352377, 10210.540525676213, 10240.897591024144, 10274.794504109876, 6530.314316996647, 6591.242007169237, 6593.604383736991, 6673.9332606673315, 6686.9331306688255, 11963.461644225858, 12121.212121212093, 12200.51197952072, 12290.832370924747, 12292.938535307267, 9581.377210481296, 11949.761004779682, 12335.198212116184, 12542.0, 12726.68183295425, 9223.077692230723, 9392.154706076486, 9394.67118650844, 9396.295277854326, 9403.717888463358, 8820.91179088201, 10032.297739158188, 10177.796444071226, 10256.743581410525, 10268.178545716473, 8207.384446166681, 8252.092269850431, 8711.607977640895, 8751.781205469913, 8804.163604457566, 8583.742487725378, 8618.956905215433, 8641.56792160402, 8709.869351959598, 8877.556122193993, 7841.294283514511, 7980.08229053643, 7990.081140668665, 7992.560409177551, 8004.679812807422, 5767.798127065533, 5775.133729940542, 5838.2702162229725, 5987.760489580368, 5998.670073146019, 5910.852228694317, 6004.339522652417, 6032.849178770566, 6079.878402431838, 6277.058441233851], "value": 5116.249237779791, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.4059411870394, 374.810720586104, 383.3961510620773, 386.1369838411151, 392.5838611072262, 4596.563326483983, 4632.86101416958, 4977.55202031817, 5240.659357141783, 5251.5798736101115, 4844.636652251086, 5150.562202212807, 5158.916627508218, 5168.173092305232, 5181.507756763111, 3406.403879321121, 3896.7530390275124, 3991.44119823225, 4274.422952901349, 4288.313869780835, 4142.875713728593, 4250.744955302692, 4320.956790432103, 4321.956780432187, 4332.696711230215, 3737.635762946532, 3939.2909276330247, 4110.77390743509, 4244.943640578794, 4403.581659742335, 4064.4716186895516, 4221.079408868957, 4229.323308270662, 4311.137772445492, 4386.2104821132025, 3500.6499350065046, 3794.31702293587, 3937.8621748238675, 3959.742616729903, 3974.284628766821, 4184.497860256758, 5046.687861156126, 5057.4689657585795, 5059.623425736748, 5076.695398276115, 4248.259872382296, 4550.453945526558, 4876.440857422541, 4969.608509617313, 4980.377452818398, 5171.65536960393, 5245.606579506552, 5511.807086751985, 5530.391656917735, 5550.417206193332, 4036.5156181258435, 4308.526062133163, 4390.209762242795, 4442.578374920027, 4449.421575195204, 4513.887152821206, 4903.681260718042, 5584.357798853184, 5907.5864689471755, 5923.17075609415, 3936.4685767421606, 4018.9799051005143, 4258.211551148525, 4437.8224871004795, 4541.591256786905, 3976.4234186042863, 4576.83981060668, 4589.449266088091, 4637.791299391535, 4651.906961860744, 4438.294024489587, 4500.774961251925, 4791.95208047922, 4951.474747878536, 4968.627352948543, 4949.4409261083365, 5505.3393592768325, 5540.487215220881, 5564.415736347706, 5572.275604171432, 1436.3321055709146, 1513.6140284227672, 1565.6555557777272, 1585.5322679809483, 1596.361455417842, 981.7349315684725, 1149.8562679665042, 1170.4264910193945, 1182.6694968160818, 1189.8155785853035, 4671.416072990876, 4774.355462012584, 4863.465018847924, 5147.356580427447, 5214.869628259248, 5691.044723916921, 5724.771009159587, 5749.016589276693, 5770.019096753562, 5772.740226689809, 4671.789769460314, 4776.1850496811685, 4970.502949705001, 5119.206522988943, 5151.407588127413, 2850.8717107730204, 2954.4386566552284, 2956.822590644568, 2969.5248760198697, 2976.3154474470994, 5038.521340472649, 5273.129933560919, 5288.286081378965, 5359.017163649474, 5497.670139791545, 5309.946900530946, 5354.437784032699, 5369.785208591613, 5475.917861232085, 5623.8875222496135, 5180.948190518197, 5189.40321862983, 5269.341332333458, 5460.180972854104, 5667.4615911488345, 5194.922076168936, 5269.841904742864, 5663.9804835128425, 5724.0, 5756.251687280626, 5158.329417175743, 5220.686758794484, 5642.492175704207, 5662.414523933306, 5679.943200567942, 5526.447355264441, 5823.010088284917, 5992.0525829104845, 5998.970005150121, 6003.459688628044, 4231.365295205744, 4600.653666629976, 4842.273658951045, 5023.648344615988, 5041.07617581809, 5107.770150343169, 5569.944300556943, 5730.942690573041, 5768.3078030634915, 5805.448482394166, 5580.837725104343, 5584.916226256691, 5586.804461843816, 5593.161025846156, 5649.887002259849, 4735.763211839463, 4877.6829506081995, 5349.806579302467, 5615.0, 5633.746481408264, 4955.042758110454, 5253.658512196695, 5363.701323685983, 5465.966388654335, 5502.58730595215, 5191.703268424502, 5204.271402003798, 5524.33707955048, 5594.957840388311, 5608.093248295599, 4663.393758811333, 5119.257707632447, 5381.811636592701, 5481.314835645544, 5500.834974950758], "value": 4227.319802112561, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.9172095209052, 738.0368618952268, 773.7137259214092, 822.1737154160069, 832.5961908474388, 15177.924110379447, 16058.59853503663, 16058.678826423464, 16085.678286434279, 16094.91952540237, 14546.418143274283, 14569.12585244886, 14669.486567970123, 14680.926595367007, 15174.696506069871, 905.7961958559338, 919.2783664823115, 928.1090153452693, 930.6975233049252, 938.6667732954805, 957.9941061884992, 960.6685693435753, 960.98615960162, 962.1052421248237, 962.870012548304, 1008.425197637347, 1010.2675560218819, 1011.1304278320638, 1011.2011510906411, 1011.3173607814732, 1003.4581326083905, 1004.1565085328286, 1005.0, 1006.1296978113916, 1006.5068116622839, 749.2919191364163, 749.5090715581302, 749.7713197474768, 749.8013026547969, 750.6809605917517, 12635.810462843065, 15404.691906161926, 15532.922335388364, 16055.357785688559, 16090.517284481368, 13660.043796934218, 13998.860011399858, 14128.364223609962, 14240.287985600678, 14250.2162381069, 11641.941790290994, 11706.707332316675, 11732.941335293353, 11733.471993760217, 12236.510539578407, 3636.799976001319, 3680.0, 3727.329080765461, 3757.4927384802977, 3862.517185351831, 114351.42824285825, 117734.0, 117780.0, 118164.40917795357, 120889.18666220014, 10879.12966962641, 11007.724806879893, 11031.117510599133, 11246.550137994553, 11313.264637798517, 22599.0, 22984.885075574515, 22991.425214369774, 22998.080076797076, 23007.88496057509, 7623.199564045806, 7692.38460923136, 7765.689372425153, 7836.725714600076, 7924.445288829842, 4231.724494925643, 4291.849785257501, 4358.782060896942, 4520.819167233274, 4584.220682483919, 3434.261633748756, 3525.2244506208594, 3528.1356067763563, 3537.593176784703, 3538.929221415557, 565.8528782516576, 580.7299605683378, 608.3855306140741, 613.4724137241968, 613.622622087422, 517.5161224255334, 628.8239292997968, 673.1988933169513, 675.8377989282537, 717.8815495443298, 9977.852546957047, 10069.949650251701, 10134.290599657954, 10170.491475426346, 10529.262951593315, 598.937111603284, 704.8237940514871, 712.3624356201171, 712.3944647049966, 719.133444199738, 4294.849680261176, 4677.415323084614, 4954.801807927714, 4955.306257124005, 4991.750412479361, 101117.4832377515, 103209.37290117072, 105272.0, 107345.92654073515, 107567.92432075736, 87990.0, 90099.64850527247, 90746.0, 92412.15175696583, 93636.06363936274, 79750.60124699456, 80017.0, 82954.0, 83086.58456707677, 83914.5804270987, 9615.759606009906, 9669.419834809934, 9710.271729620166, 9719.85420218683, 9721.951390243003, 47762.522374777196, 51419.45741627608, 51428.48571514237, 52970.2054469191, 54068.45931540634, 62549.68725156345, 69875.0, 70094.0, 70627.23431914243, 72055.63972180105, 10543.26197166191, 11041.889581104408, 11080.33517988891, 11254.268472549258, 11595.710107247387, 12108.273503589813, 12283.754324913272, 12422.130450868352, 12675.049371297006, 13300.0, 13711.931440342734, 14052.929735351257, 14146.78779818324, 14200.005999580344, 14546.0, 13477.932610336886, 13747.931260343634, 14149.929250353682, 14176.787348189579, 14304.785428218793, 11814.822777658514, 13047.804282935575, 13056.804147937599, 13256.53602123921, 14284.857151428352, 9294.349395542247, 9618.663346783109, 9846.310758246855, 10261.846072308772, 10269.229807764597, 10772.299800513256, 10942.234043617189, 11095.72260693489, 11202.159838012345, 11259.0], "value": 7622.518490559939, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 125.0, 170.0, 154.0, 116.0, 116.0, 117.0, 120.0, 167.0], "value": 130.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 111.0, 107.0, 144.0, 109.0, 109.0, 109.0, 109.0, 130.0, 109.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 185.0, 187.0, 185.0, 185.0, 184.0, 187.0, 184.0, 184.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 60.0, 92.0, 61.0, 62.0, 59.0, 60.0, 77.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 86.0, 69.0, 69.0, 68.0, 83.0, 69.0, 68.0, 83.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 89.0, 88.0, 89.0, 91.0, 134.0, 105.0, 88.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 99.0, 95.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 86.0, 83.0, 83.0, 83.0, 83.0, 82.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 57.0, 59.0, 88.0, 91.0, 92.0, 85.0, 63.0, 64.0], "value": 71.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 69.0, 63.0, 60.0, 57.0, 58.0, 61.0, 80.0, 81.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 84.0, 86.0, 84.0, 82.0, 81.0, 83.0, 77.0, 86.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 147.0, 140.0, 134.0, 131.0, 140.0, 139.0, 179.0, 134.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 166.0, 182.0, 217.0, 197.0, 166.0, 163.0, 162.0, 165.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 78.0, 80.0, 80.0, 64.0, 64.0, 62.0, 63.0, 65.0], "value": 68.9, "unit": "score"}]}]} 02:33:26 INFO - Return code: 0 02:33:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:33:26 ERROR - # TBPL SUCCESS # 02:33:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:33:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:33:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:33:27 INFO - 2017-04-07 02:33:27.012 screenresolution[1654:10005] starting screenresolution argv=screenresolution get 02:33:27 INFO - 2017-04-07 02:33:27.016 screenresolution[1654:10005] Display 0: 1600x1200x32@60 02:33:27 INFO - 2017-04-07 02:33:27.022 screenresolution[1655:10008] starting screenresolution argv=screenresolution list 02:33:27 INFO - Available Modes on Display 0 02:33:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:33:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:33:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:33:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:33:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:33:27 INFO - Intel Iris: 02:33:27 INFO - Chipset Model: Intel Iris 02:33:27 INFO - Type: GPU 02:33:27 INFO - Bus: Built-In 02:33:27 INFO - VRAM (Dynamic, Max): 1536 MB 02:33:27 INFO - Vendor: Intel (0x8086) 02:33:27 INFO - Device ID: 0x0a2e 02:33:27 INFO - Revision ID: 0x0009 02:33:27 INFO - Displays: 02:33:27 INFO - Display: 02:33:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:33:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:33:27 INFO - Display Serial Number: 1600x1200 60 02:33:27 INFO - Main Display: Yes 02:33:27 INFO - Mirror: Off 02:33:27 INFO - Online: Yes 02:33:27 INFO - Rotation: Supported 02:33:27 INFO - Return code: 0 02:33:27 INFO - Running post-action listener: _resource_record_post_action 02:33:27 INFO - [mozharness: 2017-04-07 09:33:27.257339Z] Finished run-tests step (success) 02:33:27 INFO - Running post-run listener: _resource_record_post_run 02:33:27 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 39347200; Write bytes: 508526592; Read time: 377; Write time: 1890 02:33:27 INFO - TinderboxPrint: CPU usage
54.0% 02:33:27 INFO - TinderboxPrint: I/O read bytes / time
39,347,200 / 377 02:33:27 INFO - TinderboxPrint: I/O write bytes / time
508,526,592 / 1,890 02:33:27 INFO - TinderboxPrint: CPU idle
2,211.0 (46.0%) 02:33:27 INFO - TinderboxPrint: CPU system
960.6 (20.0%) 02:33:27 INFO - TinderboxPrint: CPU user
1,634.3 (34.0%) 02:33:27 INFO - TinderboxPrint: Swap in / out
247,504,896 / 0 02:33:27 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 211237376; Write bytes: 202213376; Read time: 15770; Write time: 493 02:33:27 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31906816; Write bytes: 295249920; Read time: 287; Write time: 1360 02:33:27 INFO - Running post-run listener: _upload_blobber_files 02:33:27 INFO - Blob upload gear active. 02:33:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:33:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:33:28 INFO - (blobuploader) - INFO - Open directory for files ... 02:33:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:33:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:33:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:29 INFO - (blobuploader) - INFO - Done attempting. 02:33:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:33:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:33:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:30 INFO - (blobuploader) - INFO - Done attempting. 02:33:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:33:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:33:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:31 INFO - (blobuploader) - INFO - Done attempting. 02:33:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:33:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:33:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:33:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:33:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:33:33 INFO - (blobuploader) - INFO - Done attempting. 02:33:33 INFO - (blobuploader) - INFO - Iteration through files over. 02:33:33 INFO - Return code: 0 02:33:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:33:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:33:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc2499eb7179024c77504b86d620cdebb9ff89c63171f8f56a7d1362bb673c70add654be3c008999af36a79f116a1d3eebd4a5fcccef3ad5551978ac39e66621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1070d80c6221d733d23dc97df04024de47ac1bf6c524bd0db563fc8340a302a01d0bab1957af148cc2abd3d6dba5999ef9e20b81f0c5a85b3d00599b22e9026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6707dbf6dce917dff3ef425321365d56b0cc7e8c8829d4c65ab34a81c3e7f752b1c829bdcf24e1dd4747f0eec983a4bc979ca93e193f245880cf51f2e2e431bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51901065f984bf514b0aa59bd7719d9381463fece2ccfb6096a31708d300bc97ebfc6aa03a56fb974226b65f520c1f29466ecff32ba7a09bc245efdb6a43ee75"} 02:33:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:33:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:33:33 INFO - Contents: 02:33:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dc2499eb7179024c77504b86d620cdebb9ff89c63171f8f56a7d1362bb673c70add654be3c008999af36a79f116a1d3eebd4a5fcccef3ad5551978ac39e66621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1070d80c6221d733d23dc97df04024de47ac1bf6c524bd0db563fc8340a302a01d0bab1957af148cc2abd3d6dba5999ef9e20b81f0c5a85b3d00599b22e9026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6707dbf6dce917dff3ef425321365d56b0cc7e8c8829d4c65ab34a81c3e7f752b1c829bdcf24e1dd4747f0eec983a4bc979ca93e193f245880cf51f2e2e431bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51901065f984bf514b0aa59bd7719d9381463fece2ccfb6096a31708d300bc97ebfc6aa03a56fb974226b65f520c1f29466ecff32ba7a09bc245efdb6a43ee75"} 02:33:33 INFO - Running post-run listener: copy_logs_to_upload_dir 02:33:33 INFO - Copying logs to upload dir... 02:33:33 INFO - mkdir: /builds/slave/test/build/upload/logs 02:33:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1298.805013 ========= master_lag: 1.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2017-04-07 02:33:34.126425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 02:33:34.130286) ========= 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.Ep9V7It3Er/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.scCVat9P9f/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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/dc2499eb7179024c77504b86d620cdebb9ff89c63171f8f56a7d1362bb673c70add654be3c008999af36a79f116a1d3eebd4a5fcccef3ad5551978ac39e66621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1070d80c6221d733d23dc97df04024de47ac1bf6c524bd0db563fc8340a302a01d0bab1957af148cc2abd3d6dba5999ef9e20b81f0c5a85b3d00599b22e9026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6707dbf6dce917dff3ef425321365d56b0cc7e8c8829d4c65ab34a81c3e7f752b1c829bdcf24e1dd4747f0eec983a4bc979ca93e193f245880cf51f2e2e431bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51901065f984bf514b0aa59bd7719d9381463fece2ccfb6096a31708d300bc97ebfc6aa03a56fb974226b65f520c1f29466ecff32ba7a09bc245efdb6a43ee75"} build_url:https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011606 build_url: 'https://queue.taskcluster.net/v1/task/XRZDzFKZR6mvkKd8g3ULbQ/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/dc2499eb7179024c77504b86d620cdebb9ff89c63171f8f56a7d1362bb673c70add654be3c008999af36a79f116a1d3eebd4a5fcccef3ad5551978ac39e66621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b1070d80c6221d733d23dc97df04024de47ac1bf6c524bd0db563fc8340a302a01d0bab1957af148cc2abd3d6dba5999ef9e20b81f0c5a85b3d00599b22e9026", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6707dbf6dce917dff3ef425321365d56b0cc7e8c8829d4c65ab34a81c3e7f752b1c829bdcf24e1dd4747f0eec983a4bc979ca93e193f245880cf51f2e2e431bc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/51901065f984bf514b0aa59bd7719d9381463fece2ccfb6096a31708d300bc97ebfc6aa03a56fb974226b65f520c1f29466ecff32ba7a09bc245efdb6a43ee75"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-07 02:33:34.179478) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:33:34.179804) ========= 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.Ep9V7It3Er/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.scCVat9P9f/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005468 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-07 02:33:34.229371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 02:33:34.229688) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-07 02:33:34.230025) ========= ========= Total master_lag: 1.28 =========