builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0375 starttime: 1483723436.85 results: success (0) buildid: 20170106072356 builduid: 7dd62bd7c7a949ba8186b134e5681f53 revision: a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:56.853361) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:56.853830) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:56.854110) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:57.061355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:57.061757) ========= 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.7HBMS4hBvD/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.6N2BhrAP93/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-06 09:23:57-- 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% 18.3M=0.001s 2017-01-06 09:23:57 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127921 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:57.216601) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:57.216976) ========= 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.7HBMS4hBvD/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.6N2BhrAP93/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.025021 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:23:57.268312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-06 09:23:57.271699) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7HBMS4hBvD/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.6N2BhrAP93/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-06 09:23:57,368 truncating revision to first 12 chars 2017-01-06 09:23:57,368 Setting DEBUG logging. 2017-01-06 09:23:57,368 attempt 1/10 2017-01-06 09:23:57,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a9a67c9d3659?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 09:23:58,434 unpacking tar archive at: mozilla-central-a9a67c9d3659/testing/mozharness/ program finished with exit code 0 elapsedTime=1.326091 ========= master_lag: 1.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-06 09:24:00.226757) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 09:24:00.227109) ========= script_repo_revision: a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 09:24:00.231537) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-01-06 09:24:00.231850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-01-06 09:24:01.603373) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-06 09:24:01.603817) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7HBMS4hBvD/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.6N2BhrAP93/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:01 INFO - MultiFileLogger online at 20170106 09:24:01 in /builds/slave/test 09:24:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:24:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:24:01 INFO - {'append_to_log': False, 09:24:01 INFO - 'base_work_dir': '/builds/slave/test', 09:24:01 INFO - 'blob_upload_branch': 'Firefox', 09:24:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:24:01 INFO - 'branch': 'Firefox', 09:24:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:24:01 INFO - 'config_files': ('talos/mac_config.py',), 09:24:01 INFO - 'default_actions': ('clobber', 09:24:01 INFO - 'read-buildbot-config', 09:24:01 INFO - 'download-and-extract', 09:24:01 INFO - 'populate-webroot', 09:24:01 INFO - 'create-virtualenv', 09:24:01 INFO - 'install', 09:24:01 INFO - 'run-tests'), 09:24:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:24:01 INFO - 'download_minidump_stackwalk': True, 09:24:01 INFO - 'download_symbols': 'ondemand', 09:24:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:24:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:24:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:24:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:24:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:24:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:24:01 INFO - 'installer_path': 'installer.exe', 09:24:01 INFO - 'log_level': 'info', 09:24:01 INFO - 'log_name': 'talos', 09:24:01 INFO - 'log_to_console': True, 09:24:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:24:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:24:01 INFO - 'opt_config_files': (), 09:24:01 INFO - 'pip_index': False, 09:24:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:24:01 INFO - 'cmd': ('bash', 09:24:01 INFO - '-c', 09:24:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:24:01 INFO - 'enabled': True, 09:24:01 INFO - 'halt_on_failure': False, 09:24:01 INFO - 'name': 'check_screen_resolution'},), 09:24:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:24:01 INFO - 'cmd': ('bash', 09:24:01 INFO - '-c', 09:24:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:24:01 INFO - 'enabled': True, 09:24:01 INFO - 'halt_on_failure': False, 09:24:01 INFO - 'name': 'check_screen_resolution'},), 09:24:01 INFO - 'run_cmd_checks_enabled': True, 09:24:01 INFO - 'sps_profile': False, 09:24:01 INFO - 'sps_profile_interval': 0, 09:24:01 INFO - 'suite': 'dromaeojs', 09:24:01 INFO - 'system_bits': '32', 09:24:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:24:01 INFO - 'title': 't-yosemite-r7-0375', 09:24:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:24:01 INFO - 'use_talos_json': True, 09:24:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:24:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:24:01 INFO - 'work_dir': 'build'} 09:24:01 INFO - [mozharness: 2017-01-06 17:24:01.803720Z] Running clobber step. 09:24:01 INFO - Running pre-action listener: _resource_record_pre_action 09:24:01 INFO - Running main action method: clobber 09:24:01 INFO - rmtree: /builds/slave/test/build 09:24:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:24:04 INFO - Running post-action listener: _resource_record_post_action 09:24:04 INFO - [mozharness: 2017-01-06 17:24:04.030998Z] Finished clobber step (success) 09:24:04 INFO - [mozharness: 2017-01-06 17:24:04.031160Z] Running read-buildbot-config step. 09:24:04 INFO - Running pre-action listener: _resource_record_pre_action 09:24:04 INFO - Running main action method: read_buildbot_config 09:24:04 INFO - Using buildbot properties: 09:24:04 INFO - { 09:24:04 INFO - "project": "", 09:24:04 INFO - "product": "firefox", 09:24:04 INFO - "script_repo_revision": "production", 09:24:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:24:04 INFO - "repository": "", 09:24:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:24:04 INFO - "buildid": "20170106072356", 09:24:04 INFO - "pgo_build": "False", 09:24:04 INFO - "basedir": "/builds/slave/test", 09:24:04 INFO - "buildnumber": 49, 09:24:04 INFO - "slavename": "t-yosemite-r7-0375", 09:24:04 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:24:04 INFO - "platform": "yosemite_r7", 09:24:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:24:04 INFO - "branch": "mozilla-central", 09:24:04 INFO - "repo_path": "mozilla-central", 09:24:04 INFO - "revision": "a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360", 09:24:04 INFO - "stage_platform": "macosx64", 09:24:04 INFO - "builduid": "7dd62bd7c7a949ba8186b134e5681f53", 09:24:04 INFO - "slavebuilddir": "test" 09:24:04 INFO - } 09:24:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:24:04 INFO - Running post-action listener: _resource_record_post_action 09:24:04 INFO - [mozharness: 2017-01-06 17:24:04.034566Z] Finished read-buildbot-config step (success) 09:24:04 INFO - [mozharness: 2017-01-06 17:24:04.034680Z] Running download-and-extract step. 09:24:04 INFO - Running pre-action listener: _resource_record_pre_action 09:24:04 INFO - Running main action method: download_and_extract 09:24:04 INFO - mkdir: /builds/slave/test/build/tests 09:24:04 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')]} 09:24:04 INFO - trying https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:24:04 INFO - Downloading https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:24:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:24:05 INFO - Downloaded 1199 bytes. 09:24:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:24:05 INFO - Using the following test package requirements: 09:24:05 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:24:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:24:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:24:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'jsshell-mac.zip'], 09:24:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:24:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:24:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:24:05 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:24:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:24:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:24:05 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:24:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:24:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:24:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:24:05 INFO - Fetch https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:24:06 INFO - Expected file size: 16300126 09:24:06 INFO - Obtained file size: 16300126 09:24:12 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:24:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:24:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:24:12 INFO - Fetch https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:24:13 INFO - Expected file size: 16300126 09:24:13 INFO - Obtained file size: 16300126 09:24:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:24:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:24:20 INFO - Fetch https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:24:21 INFO - Expected file size: 14403730 09:24:21 INFO - Obtained file size: 14403730 09:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:21 INFO - trying https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:24:21 INFO - Downloading https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:24:23 INFO - Downloaded 69711743 bytes. 09:24:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:24:23 INFO - mkdir: /builds/slave/test/properties 09:24:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:24:23 INFO - Writing to file /builds/slave/test/properties/build_url 09:24:23 INFO - Contents: 09:24:23 INFO - build_url:https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:24:24 INFO - Running post-action listener: _resource_record_post_action 09:24:24 INFO - Running post-action listener: set_extra_try_arguments 09:24:24 INFO - [mozharness: 2017-01-06 17:24:24.669595Z] Finished download-and-extract step (success) 09:24:24 INFO - [mozharness: 2017-01-06 17:24:24.669745Z] Running populate-webroot step. 09:24:24 INFO - Running pre-action listener: _resource_record_pre_action 09:24:24 INFO - Running main action method: populate_webroot 09:24:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:24:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:24:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:24:24 INFO - '--tppagecycles', 09:24:24 INFO - '7'], 09:24:24 INFO - 'tests': ['tsvgm']}}, 09:24:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:24:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:24:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:24:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:24:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:24:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['damp', 'tps']}, 09:24:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'tests': ['damp', 'tps']}, 09:24:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['dromaeo_dom']}, 09:24:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:24:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['basic_compositor_video']}, 09:24:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:24:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['a11yr', 09:24:24 INFO - 'ts_paint', 09:24:24 INFO - 'tpaint', 09:24:24 INFO - 'sessionrestore', 09:24:24 INFO - 'sessionrestore_no_auto_restore', 09:24:24 INFO - 'tabpaint']}, 09:24:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:24:24 INFO - 'ts_paint', 09:24:24 INFO - 'tpaint', 09:24:24 INFO - 'sessionrestore', 09:24:24 INFO - 'sessionrestore_no_auto_restore', 09:24:24 INFO - 'tabpaint']}, 09:24:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:24:24 INFO - 'ts_paint', 09:24:24 INFO - 'tpaint', 09:24:24 INFO - 'sessionrestore', 09:24:24 INFO - 'sessionrestore_no_auto_restore', 09:24:24 INFO - 'tabpaint']}, 09:24:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:24:24 INFO - 'ts_paint', 09:24:24 INFO - 'tpaint', 09:24:24 INFO - 'sessionrestore', 09:24:24 INFO - 'sessionrestore_no_auto_restore', 09:24:24 INFO - 'tabpaint']}, 09:24:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['a11yr', 09:24:24 INFO - 'ts_paint', 09:24:24 INFO - 'tpaint', 09:24:24 INFO - 'sessionrestore', 09:24:24 INFO - 'sessionrestore_no_auto_restore', 09:24:24 INFO - 'tabpaint']}, 09:24:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['a11yr', 09:24:24 INFO - 'ts_paint', 09:24:24 INFO - 'tpaint', 09:24:24 INFO - 'sessionrestore', 09:24:24 INFO - 'sessionrestore_no_auto_restore', 09:24:24 INFO - 'tabpaint']}, 09:24:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['tsvgx', 09:24:24 INFO - 'tsvgr_opacity', 09:24:24 INFO - 'tart', 09:24:24 INFO - 'tscrollx', 09:24:24 INFO - 'cart', 09:24:24 INFO - 'tsvg_static']}, 09:24:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:24:24 INFO - 'tsvgr_opacity', 09:24:24 INFO - 'tart', 09:24:24 INFO - 'tscrollx', 09:24:24 INFO - 'cart', 09:24:24 INFO - 'tsvg_static']}, 09:24:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'talos_options': ['--disable-e10s'], 09:24:24 INFO - 'tests': ['tp5o']}, 09:24:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:24:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'talos_options': ['--disable-e10s', 09:24:24 INFO - '--xperf_path', 09:24:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:24 INFO - 'tests': ['tp5n']}, 09:24:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:24:24 INFO - 'talos_options': ['--xperf_path', 09:24:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:24:24 INFO - 'tests': ['tp5n']}}, 09:24:24 INFO - 'talos.zip': {'path': '', 09:24:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:24:24 INFO - Running post-action listener: _resource_record_post_action 09:24:24 INFO - [mozharness: 2017-01-06 17:24:24.688387Z] Finished populate-webroot step (success) 09:24:24 INFO - [mozharness: 2017-01-06 17:24:24.688499Z] Running create-virtualenv step. 09:24:24 INFO - Running pre-action listener: _resource_record_pre_action 09:24:24 INFO - Running main action method: create_virtualenv 09:24:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:24:24 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 09:24:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:24:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:24:24 INFO - Using real prefix '/tools/python27' 09:24:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:24:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:24:28 INFO - Installing pip.................done. 09:24:28 INFO - Return code: 0 09:24:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:24:28 INFO - Reading from file tmpfile_stdout 09:24:28 INFO - Output received: 09:24:28 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) 09:24:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:24:28 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')]} 09:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 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')]} 09:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:28 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 09:24:28 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 09:24:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:28 INFO - 'HOME': '/Users/cltbld', 09:24:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:28 INFO - 'LOGNAME': 'cltbld', 09:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:28 INFO - 'MOZ_NO_REMOTE': '1', 09:24:28 INFO - 'NO_EM_RESTART': '1', 09:24:28 INFO - 'PAGER': '/bin/cat', 09:24:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:28 INFO - 'PWD': '/builds/slave/test', 09:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:28 INFO - 'SHELL': '/bin/bash', 09:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:28 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:28 INFO - 'USER': 'cltbld', 09:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:28 INFO - 'XPC_FLAGS': '0x0', 09:24:28 INFO - 'XPC_SERVICE_NAME': '0', 09:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:28 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 09:24:28 INFO - Cleaning up... 09:24:28 INFO - Return code: 0 09:24:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:24:28 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')]} 09:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 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')]} 09:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:28 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:28 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 09:24:28 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 09:24:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:28 INFO - 'HOME': '/Users/cltbld', 09:24:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:28 INFO - 'LOGNAME': 'cltbld', 09:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:28 INFO - 'MOZ_NO_REMOTE': '1', 09:24:28 INFO - 'NO_EM_RESTART': '1', 09:24:28 INFO - 'PAGER': '/bin/cat', 09:24:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:28 INFO - 'PWD': '/builds/slave/test', 09:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:28 INFO - 'SHELL': '/bin/bash', 09:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:28 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:28 INFO - 'USER': 'cltbld', 09:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:28 INFO - 'XPC_FLAGS': '0x0', 09:24:28 INFO - 'XPC_SERVICE_NAME': '0', 09:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:29 INFO - Downloading/unpacking psutil>=3.1.1 09:24:29 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 09:24:29 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 09:24:29 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 09:24:29 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 09:24:29 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 09:24:29 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 09:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:24:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:32 INFO - Installing collected packages: psutil 09:24:32 INFO - Running setup.py install for psutil 09:24:32 INFO - building 'psutil._psutil_osx' extension 09:24:32 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 09:24:33 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 09:24:33 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 09:24:33 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 09:24:33 INFO - building 'psutil._psutil_posix' extension 09:24:33 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 09:24:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:24:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:24:33 INFO - ^ 09:24:33 INFO - 1 warning generated. 09:24:33 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 09:24:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:24:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:24:33 INFO - Successfully installed psutil 09:24:33 INFO - Cleaning up... 09:24:33 INFO - Return code: 0 09:24:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:24:33 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')]} 09:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:33 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')]} 09:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:33 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:33 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 09:24:33 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 09:24:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:33 INFO - 'HOME': '/Users/cltbld', 09:24:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:33 INFO - 'LOGNAME': 'cltbld', 09:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:33 INFO - 'MOZ_NO_REMOTE': '1', 09:24:33 INFO - 'NO_EM_RESTART': '1', 09:24:33 INFO - 'PAGER': '/bin/cat', 09:24:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:33 INFO - 'PWD': '/builds/slave/test', 09:24:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:33 INFO - 'SHELL': '/bin/bash', 09:24:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:33 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:33 INFO - 'USER': 'cltbld', 09:24:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:33 INFO - 'XPC_FLAGS': '0x0', 09:24:33 INFO - 'XPC_SERVICE_NAME': '0', 09:24:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:24:36 INFO - Installing collected packages: mozsystemmonitor 09:24:36 INFO - Running setup.py install for mozsystemmonitor 09:24:36 INFO - Successfully installed mozsystemmonitor 09:24:36 INFO - Cleaning up... 09:24:37 INFO - Return code: 0 09:24:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:37 INFO - 'HOME': '/Users/cltbld', 09:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:37 INFO - 'LOGNAME': 'cltbld', 09:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:37 INFO - 'MOZ_NO_REMOTE': '1', 09:24:37 INFO - 'NO_EM_RESTART': '1', 09:24:37 INFO - 'PAGER': '/bin/cat', 09:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:37 INFO - 'PWD': '/builds/slave/test', 09:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:37 INFO - 'SHELL': '/bin/bash', 09:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:37 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:37 INFO - 'USER': 'cltbld', 09:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:37 INFO - 'XPC_FLAGS': '0x0', 09:24:37 INFO - 'XPC_SERVICE_NAME': '0', 09:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:37 INFO - Downloading/unpacking jsonschema==2.5.1 09:24:37 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 09:24:37 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 09:24:37 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 09:24:37 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 09:24:37 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 09:24:37 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 09:24:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:24:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:40 INFO - Downloading functools32-3.2.3-2.tar.gz 09:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:24:40 INFO - warning: no files found matching '*.txt' 09:24:40 INFO - no previously-included directories found matching 'build' 09:24:40 INFO - no previously-included directories found matching 'dist' 09:24:40 INFO - no previously-included directories found matching '.git*' 09:24:40 INFO - Installing collected packages: jsonschema, functools32 09:24:40 INFO - Running setup.py install for functools32 09:24:40 INFO - warning: no files found matching '*.txt' 09:24:40 INFO - no previously-included directories found matching 'build' 09:24:40 INFO - no previously-included directories found matching 'dist' 09:24:40 INFO - no previously-included directories found matching '.git*' 09:24:40 INFO - Successfully installed jsonschema functools32 09:24:40 INFO - Cleaning up... 09:24:40 INFO - Return code: 0 09:24:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:24:40 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')]} 09:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:40 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')]} 09:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:40 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:40 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 09:24:40 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 09:24:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:40 INFO - 'HOME': '/Users/cltbld', 09:24:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:40 INFO - 'LOGNAME': 'cltbld', 09:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:40 INFO - 'MOZ_NO_REMOTE': '1', 09:24:40 INFO - 'NO_EM_RESTART': '1', 09:24:40 INFO - 'PAGER': '/bin/cat', 09:24:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:40 INFO - 'PWD': '/builds/slave/test', 09:24:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:40 INFO - 'SHELL': '/bin/bash', 09:24:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:40 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:40 INFO - 'USER': 'cltbld', 09:24:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:40 INFO - 'XPC_FLAGS': '0x0', 09:24:40 INFO - 'XPC_SERVICE_NAME': '0', 09:24:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:24:40 INFO - Cleaning up... 09:24:40 INFO - Return code: 0 09:24:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:24:40 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')]} 09:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:40 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')]} 09:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:40 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:40 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 09:24:40 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 09:24:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:40 INFO - 'HOME': '/Users/cltbld', 09:24:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:40 INFO - 'LOGNAME': 'cltbld', 09:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:40 INFO - 'MOZ_NO_REMOTE': '1', 09:24:40 INFO - 'NO_EM_RESTART': '1', 09:24:40 INFO - 'PAGER': '/bin/cat', 09:24:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:40 INFO - 'PWD': '/builds/slave/test', 09:24:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:40 INFO - 'SHELL': '/bin/bash', 09:24:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:40 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:40 INFO - 'USER': 'cltbld', 09:24:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:40 INFO - 'XPC_FLAGS': '0x0', 09:24:40 INFO - 'XPC_SERVICE_NAME': '0', 09:24:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:41 INFO - Downloading/unpacking blobuploader==1.2.4 09:24:41 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 09:24:41 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 09:24:41 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 09:24:41 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 09:24:41 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 09:24:41 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 09:24:43 INFO - Downloading blobuploader-1.2.4.tar.gz 09:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:24:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:24:43 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 09:24:43 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 09:24:43 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 09:24:43 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 09:24:43 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 09:24:43 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 09:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:24:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:45 INFO - Downloading docopt-0.6.1.tar.gz 09:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:24:45 INFO - Installing collected packages: blobuploader, requests, docopt 09:24:45 INFO - Running setup.py install for blobuploader 09:24:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:24:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:24:45 INFO - Running setup.py install for requests 09:24:45 INFO - Running setup.py install for docopt 09:24:46 INFO - Successfully installed blobuploader requests docopt 09:24:46 INFO - Cleaning up... 09:24:46 INFO - Return code: 0 09:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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')]} 09:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:47 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:47 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 09:24:47 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 09:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:47 INFO - 'HOME': '/Users/cltbld', 09:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:47 INFO - 'LOGNAME': 'cltbld', 09:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:47 INFO - 'MOZ_NO_REMOTE': '1', 09:24:47 INFO - 'NO_EM_RESTART': '1', 09:24:47 INFO - 'PAGER': '/bin/cat', 09:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:47 INFO - 'PWD': '/builds/slave/test', 09:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:47 INFO - 'SHELL': '/bin/bash', 09:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:47 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:47 INFO - 'USER': 'cltbld', 09:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:47 INFO - 'XPC_FLAGS': '0x0', 09:24:47 INFO - 'XPC_SERVICE_NAME': '0', 09:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-AG651y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-y_z1M9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-9fYq0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-VYmunZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TurqhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:47 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-9cuFV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-StG7Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_qNAlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-IBV0Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-1qW1vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-CVDkXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-h20cJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-U0vPnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ftSVQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:48 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-sa5w_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:49 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-upw5K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:49 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-dpE4Rf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:24:49 INFO - Running setup.py install for manifestparser 09:24:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:24:49 INFO - Running setup.py install for mozcrash 09:24:49 INFO - Running setup.py install for mozdebug 09:24:49 INFO - Running setup.py install for mozdevice 09:24:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:24:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:24:49 INFO - Running setup.py install for mozfile 09:24:49 INFO - Running setup.py install for mozhttpd 09:24:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Running setup.py install for mozinfo 09:24:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Running setup.py install for mozInstall 09:24:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Running setup.py install for mozleak 09:24:50 INFO - Running setup.py install for mozlog 09:24:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Running setup.py install for moznetwork 09:24:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:24:50 INFO - Running setup.py install for mozprocess 09:24:51 INFO - Running setup.py install for mozprofile 09:24:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Running setup.py install for mozrunner 09:24:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Running setup.py install for mozscreenshot 09:24:51 INFO - Running setup.py install for moztest 09:24:51 INFO - Running setup.py install for mozversion 09:24:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:24:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:24:51 INFO - Cleaning up... 09:24:51 INFO - Return code: 0 09:24:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24:51 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')]} 09:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:51 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')]} 09:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:52 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:52 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 09:24:52 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 09:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:52 INFO - 'HOME': '/Users/cltbld', 09:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:52 INFO - 'LOGNAME': 'cltbld', 09:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:52 INFO - 'MOZ_NO_REMOTE': '1', 09:24:52 INFO - 'NO_EM_RESTART': '1', 09:24:52 INFO - 'PAGER': '/bin/cat', 09:24:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:52 INFO - 'PWD': '/builds/slave/test', 09:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:52 INFO - 'SHELL': '/bin/bash', 09:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:52 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:52 INFO - 'USER': 'cltbld', 09:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:52 INFO - 'XPC_FLAGS': '0x0', 09:24:52 INFO - 'XPC_SERVICE_NAME': '0', 09:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:24:52 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-RCa25g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:24:52 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)) 09:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:24:52 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-BloWjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:24:52 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)) 09:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:24:52 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-HCjpD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:24:52 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)) 09:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:24:52 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-nAMaeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:24:52 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)) 09:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:24:52 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-DFw9fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TDgfuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-2eA41v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-WQoZ0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_hLgyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-YL8LaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-tQb1us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-LiIvN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-TDPbt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_s2hAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:24:53 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)) 09:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:53 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-JAYvSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:24:54 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)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:24:54 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-aYGeKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:24:54 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)) 09:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:24:54 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-V7C3RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:24:54 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)) 09:24:54 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)) 09:24:54 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)) 09:24:54 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)) 09:24:54 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)) 09:24:54 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)) 09:24:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:24:57 INFO - Downloading blessings-1.6.tar.gz 09:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:24:57 INFO - Installing collected packages: blessings 09:24:57 INFO - Running setup.py install for blessings 09:24:57 INFO - Successfully installed blessings 09:24:57 INFO - Cleaning up... 09:24:57 INFO - Return code: 0 09:24:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:57 INFO - Reading from file tmpfile_stdout 09:24:57 INFO - Current package versions: 09:24:57 INFO - blessings == 1.6 09:24:57 INFO - blobuploader == 1.2.4 09:24:57 INFO - docopt == 0.6.1 09:24:57 INFO - functools32 == 3.2.3-2 09:24:57 INFO - jsonschema == 2.5.1 09:24:57 INFO - manifestparser == 1.1 09:24:57 INFO - mozInstall == 1.12 09:24:57 INFO - mozcrash == 1.0 09:24:57 INFO - mozdebug == 0.1 09:24:57 INFO - mozdevice == 0.49 09:24:57 INFO - mozfile == 1.2 09:24:57 INFO - mozhttpd == 0.7 09:24:57 INFO - mozinfo == 0.9 09:24:57 INFO - mozleak == 0.1 09:24:57 INFO - mozlog == 3.4 09:24:57 INFO - moznetwork == 0.27 09:24:57 INFO - mozprocess == 0.23 09:24:57 INFO - mozprofile == 0.28 09:24:57 INFO - mozrunner == 6.13 09:24:57 INFO - mozscreenshot == 0.1 09:24:57 INFO - mozsystemmonitor == 0.3 09:24:57 INFO - moztest == 0.8 09:24:57 INFO - mozversion == 1.4 09:24:57 INFO - psutil == 3.1.1 09:24:57 INFO - requests == 1.2.3 09:24:57 INFO - wsgiref == 0.1.2 09:24:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:24: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')]} 09:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24: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')]} 09:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:57 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 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:57 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 09:24:57 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 09:24:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:57 INFO - 'HOME': '/Users/cltbld', 09:24:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:57 INFO - 'LOGNAME': 'cltbld', 09:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:57 INFO - 'MOZ_NO_REMOTE': '1', 09:24:57 INFO - 'NO_EM_RESTART': '1', 09:24:57 INFO - 'PAGER': '/bin/cat', 09:24:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:57 INFO - 'PWD': '/builds/slave/test', 09:24:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:57 INFO - 'SHELL': '/bin/bash', 09:24:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:57 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:57 INFO - 'USER': 'cltbld', 09:24:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:57 INFO - 'XPC_FLAGS': '0x0', 09:24:57 INFO - 'XPC_SERVICE_NAME': '0', 09:24:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 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)) 09:24:58 INFO - Cleaning up... 09:24:58 INFO - Return code: 0 09:24:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1055c7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ca768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83f2cab220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, '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.7HBMS4hBvD/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.6N2BhrAP93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 09:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:24:58 INFO - 'HOME': '/Users/cltbld', 09:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:24:58 INFO - 'LOGNAME': 'cltbld', 09:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:24:58 INFO - 'MOZ_NO_REMOTE': '1', 09:24:58 INFO - 'NO_EM_RESTART': '1', 09:24:58 INFO - 'PAGER': '/bin/cat', 09:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:24:58 INFO - 'PWD': '/builds/slave/test', 09:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:24:58 INFO - 'SHELL': '/bin/bash', 09:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:24:58 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:24:58 INFO - 'USER': 'cltbld', 09:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:24:58 INFO - 'XPC_FLAGS': '0x0', 09:24:58 INFO - 'XPC_SERVICE_NAME': '0', 09:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:24:58 INFO - Cleaning up... 09:24:58 INFO - Return code: 0 09:24:58 INFO - Running post-action listener: _resource_record_post_action 09:24:58 INFO - Running post-action listener: _start_resource_monitoring 09:24:58 INFO - Starting resource monitoring. 09:24:58 INFO - [mozharness: 2017-01-06 17:24:58.448150Z] Finished create-virtualenv step (success) 09:24:58 INFO - [mozharness: 2017-01-06 17:24:58.449173Z] Running install step. 09:24:58 INFO - Running pre-action listener: _resource_record_pre_action 09:24:58 INFO - Running main action method: install 09:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:24:58 INFO - Reading from file tmpfile_stdout 09:24:58 INFO - Detecting whether we're running mozinstall >=1.0... 09:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:24:58 INFO - Reading from file tmpfile_stdout 09:24:58 INFO - Output received: 09:24:58 INFO - Usage: mozinstall [options] installer 09:24:58 INFO - Options: 09:24:58 INFO - -h, --help show this help message and exit 09:24:58 INFO - -d DEST, --destination=DEST 09:24:58 INFO - Directory to install application into. [default: 09:24:58 INFO - "/builds/slave/test"] 09:24:58 INFO - --app=APP Application being installed. [default: firefox] 09:24:58 INFO - mkdir: /builds/slave/test/build/application 09:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:25:20 INFO - Reading from file tmpfile_stdout 09:25:20 INFO - Output received: 09:25:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:25:20 INFO - Running post-action listener: _resource_record_post_action 09:25:20 INFO - [mozharness: 2017-01-06 17:25:20.456749Z] Finished install step (success) 09:25:20 INFO - [mozharness: 2017-01-06 17:25:20.456904Z] Running run-tests step. 09:25:20 INFO - Running pre-action listener: _resource_record_pre_action 09:25:20 INFO - Running main action method: run_tests 09:25:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:25:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:25:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:25:20 INFO - 2017-01-06 09:25:20.471 screenresolution[1627:7337] starting screenresolution argv=screenresolution get 09:25:20 INFO - 2017-01-06 09:25:20.474 screenresolution[1627:7337] Display 0: 1600x1200x32@60 09:25:20 INFO - 2017-01-06 09:25:20.479 screenresolution[1628:7339] starting screenresolution argv=screenresolution list 09:25:20 INFO - Available Modes on Display 0 09:25:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:25:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:25:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:25:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:25:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:25:20 INFO - Intel Iris: 09:25:20 INFO - Chipset Model: Intel Iris 09:25:20 INFO - Type: GPU 09:25:20 INFO - Bus: Built-In 09:25:20 INFO - VRAM (Dynamic, Max): 1536 MB 09:25:20 INFO - Vendor: Intel (0x8086) 09:25:20 INFO - Device ID: 0x0a2e 09:25:20 INFO - Revision ID: 0x0009 09:25:20 INFO - Displays: 09:25:20 INFO - Display: 09:25:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:25:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:25:20 INFO - Display Serial Number: 1600x1200 60 09:25:20 INFO - Main Display: Yes 09:25:20 INFO - Mirror: Off 09:25:20 INFO - Online: Yes 09:25:20 INFO - Rotation: Supported 09:25:20 INFO - Return code: 0 09:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:25:20 INFO - Python 2.7.3 09:25:20 INFO - Return code: 0 09:25:20 INFO - grabbing minidump binary from tooltool 09:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:20 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 0x1053a5d30>, '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 0x1052ccdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10539abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:25:20 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 09:25:20 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 09:25:20 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 09:25:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:25:20 INFO - Return code: 0 09:25:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:25:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:25:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:25:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:25:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 09:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 09:25:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7HBMS4hBvD/Render', 09:25:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:25:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:25:20 INFO - 'HOME': '/Users/cltbld', 09:25:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:25:20 INFO - 'LOGNAME': 'cltbld', 09:25:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:25:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:25:20 INFO - 'MOZ_NO_REMOTE': '1', 09:25:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:25:20 INFO - 'NO_EM_RESTART': '1', 09:25:20 INFO - 'PAGER': '/bin/cat', 09:25:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:25:20 INFO - 'PWD': '/builds/slave/test', 09:25:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:25:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:25:20 INFO - 'SHELL': '/bin/bash', 09:25:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6N2BhrAP93/Listeners', 09:25:20 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 09:25:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:25:20 INFO - 'USER': 'cltbld', 09:25:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:25:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:25:20 INFO - 'XPC_FLAGS': '0x0', 09:25:20 INFO - 'XPC_SERVICE_NAME': '0', 09:25:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:25:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 09:25:21 INFO - mozversion application_buildid: 20170106072356 09:25:21 INFO - mozversion application_changeset: a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360 09:25:21 INFO - mozversion application_display_name: Nightly 09:25:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:25:21 INFO - mozversion application_name: Firefox 09:25:21 INFO - mozversion application_remotingname: firefox 09:25:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:25:21 INFO - mozversion application_vendor: Mozilla 09:25:21 INFO - mozversion application_version: 53.0a1 09:25:21 INFO - mozversion platform_buildid: 20170106072356 09:25:21 INFO - mozversion platform_changeset: a9a67c9d3659fcd3dc4f5e5cdf1f889fd918e360 09:25:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:25:21 INFO - mozversion platform_version: 53.0a1 09:25:21 INFO - using testdate: 1483723521 09:25:21 INFO - actual date: 1483723521 09:25:21 INFO - starting webserver on 'localhost:49245' 09:25:21 INFO - SUITE-START | Running 2 tests 09:25:21 INFO - TEST-START | dromaeo_css 09:25:21 INFO - operating with platform_type : mac_ 09:25:21 INFO - Initialising browser for dromaeo_css test... 09:25:21 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp40c7BL/profile http://localhost:49245/getInfo.html) 09:25:21 INFO - PROCESS | 1638 | 1483723521598 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:25:23 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 09:25:23 INFO - PROCESS | 1638 | colorDepth:24 09:25:23 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 09:25:23 INFO - PROCESS | 1638 | __metrics 09:25:23 INFO - PROCESS | 1638 | 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" 09:25:23 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:24 INFO - TEST-INFO | 1638: exit 0 09:25:24 INFO - Browser initialized. 09:25:24 INFO - Running cycle 1/1 for dromaeo_css test... 09:25:24 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp40c7BL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:36 INFO - PROCESS | 1640 | RSS: Main: 234622976 09:25:36 INFO - PROCESS | 1640 | 09:28:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 09:28:42 INFO - PROCESS | 1640 | RSS: Main: 488226816 09:28:42 INFO - PROCESS | 1640 | 09:30:24 INFO - PROCESS | 1640 | ************************* 09:30:24 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 09:30:24 INFO - PROCESS | 1640 | 09:30:24 INFO - PROCESS | 1640 | Full message: ReferenceError: fetch is not defined 09:30:24 INFO - PROCESS | 1640 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:30:24 INFO - PROCESS | 1640 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:30:24 INFO - PROCESS | 1640 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:30:24 INFO - PROCESS | 1640 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:30:24 INFO - PROCESS | 1640 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:30:24 INFO - PROCESS | 1640 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:30:24 INFO - PROCESS | 1640 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:30:24 INFO - PROCESS | 1640 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:30:24 INFO - PROCESS | 1640 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:30:24 INFO - PROCESS | 1640 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:30:24 INFO - PROCESS | 1640 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:30:24 INFO - PROCESS | 1640 | 09:30:24 INFO - PROCESS | 1640 | ************************* 09:31:45 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 09:31:46 INFO - PROCESS | 1640 | RSS: Main: 365662208 09:31:46 INFO - PROCESS | 1640 | 09:34:48 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 09:34:49 INFO - PROCESS | 1640 | RSS: Main: 342355968 09:34:49 INFO - PROCESS | 1640 | 09:37:51 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 09:37:51 INFO - PROCESS | 1640 | RSS: Main: 344465408 09:37:51 INFO - PROCESS | 1640 | 09:40:55 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 09:40:55 INFO - PROCESS | 1640 | RSS: Main: 327925760 09:40:55 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 09:43:58 INFO - PROCESS | 1640 | RSS: Main: 536997888 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | __start_tp_report 09:43:58 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 09:43:58 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 09:43:58 INFO - PROCESS | 1640 | |i|pagename|runs| 09:43:58 INFO - PROCESS | 1640 | |0;dojo.html;3343.063942096213;3345.2807646356027;3364.8990530284086;3380.154961259685;3419.1281223288056;4287.957120428795;5577.358603760569;5664.065429204186;5669.574781891359;5698.2877140357405;5596.468335508121;5679.460451257134;5738.3687794342695;5750.166225897243;5764.913526297109;5087.6184286178495;5120.948790512084;5146.330976973007;5165.121929272014;5208.635395522315;5198.194279886624;5267.868303292429;5278;5280.683159010452;5338.839834804962;5106.744662766847;5143.125668636303;5219.5041471060185;5221.660592061542;5277.683338999673;5140.1518749406305;5208.984248071628;5215.608829337777;5226.2944502492055;5265.052290587692;5164.018836421105;5168.483151684802;5201.011807756552;5226.189940559219;5249.2388603652635;5704.971475142639;5731.914021289684;5738.856528586777;5763.452472015152;5789.594728369016;6124.540659450559;6147.801178770142;6158.753649854045;6166.321704612534;6283.748650053993;6254;6273.278572964122;6292.590981586182;6316.368363163647;6336.081268216129;5811.070228763378;5811.215485909433;5818.679972601548;5820.505257053198;5849.883002339972;6129.785457508966;6230.501559875199;6293.244810622755;6297.023961286008;6298.559100862895;4758;5079.339685840817;6063.757449701962;6090.695465226721;6096;6107.61364822091;6176.135341052256;6259.311475737665;6259.405356491126;6273.592216505913;6102.489251635897;6102.587020209561;6104.246950856165;6133.775191399728;6265.561410701207;6254.124422580751;6257.593256438408;6265.27949285838;6271.811845644546;6273.560850740404;4855.077535268288;4884.242942343943;4899.485554016848;4916.70499770015;4985.975070124626;4407.294832826732;4410.867673969786;4438.911221775546;4444.799984000728;4463.598276155161;5814.476697097281;5818.359980402152;5833.125031245348;5837;5884.7940322088525;4611.677182597253;5998.340182579913;6164.7842325518395;6307.621542707452;6322.209723784527;5722.713864306768;5757.6833274169485;5759.798407055733;5807.186993820868;5823.505002074787;5366;5399.811006614828;5404.729763511808;5409.972950135303;5431.456854314616;6218.875622487615;6328.6202827830475;6355.459785918156;6355.491560675227;6367.649779262185;6195.039768835838;6196.783112591038;6233.345498722569;6261.405166509175;6274.592151510137;6299.842503937438;6311.2426508819235;6323.841903952438;6325.177726895474;6328.746850126127;6156.230471191101;6162.753489860355;6199.56603037783;6200.038993955944;6206.193194884636;6117.3707050999965;6171.506279497539;6198.535109866868;6219.036049412348;6273.686315684289;6112.989854784024;6158.784442544489;6163.537734670007;6168;6179.567430279837;5979.222701048836;6018.187544681412;6119.541034422347;6129.632222066691;6166.352532984151;5874.324452687995;5959.9570075237525;5969.582129251085;5977.6114552553945;5986.191864098291;6084.90872636902;6097.2683278006925;6100.816975490565;6105.419985101408;6113.5414843886;5806.854828629318;5858.2677165354335;5914.378990206139;5924.585279030426;5944.8811023780145 09:43:58 INFO - PROCESS | 1640 | |1;ext.html;492.2616075886171;495.07668198809233;497.5472320188629;506.0385267990816;507.4544864270909;34683;39036.80481597591;39914.60085399149;40938.7953060235;42714.14571708572;41629.791851040776;42031.57968420315;42115.368269475985;42268.78865605676;42391.78804105975;8449.95775021124;8677;9233.261339092891;9453.905460945405;9473.71578852632;11343.659690209306;11655.475503602316;11662.475188616532;11830.7633847323;11858.703532411673;11383;11457.885421145807;11472.082233421306;11476.196666233367;11513.597024104158;11349.716257093598;11413.201075924688;11435.085193184566;11435.828462573068;11440.485178166919;11301.434928253555;11369.943150284196;11374.658760237206;11378.772424551546;11391.259568128127;39554.40668389977;39588.40617390742;39607.405888911686;39640.60359396398;39681.404778928336;3969.2458432897656;3972.5905137791324;3986.1031267964768;4001.099752555681;4054.979725101385;29508.70491295081;30004.69995300041;30114.54828177579;30378.54432183519;31145.065648030595;2988.13344130202;3088.5830412894197;3166.6358368787655;3181.5704879841155;3280;434870;437998;438394;439664;442293;54120.72939635276;54417.72791136019;54429.45570544323;54442.911141776945;54580.72709636506;56831;56850.715746421;56971.71514142403;57011.71494142503;57084;14618.415263389557;15162.24188790556;15166.620834479227;15174.089554626758;15181.240937953058;10506.527206275736;10771.730706732395;11550.364729939934;11660.76678466426;11731.941340293244;17312.740308895376;17355.392561260294;17360.826391736173;17362;17453.040082795567;6403.583767055126;7043.759870459646;7245;7259.9274007259255;7323.780286591411;732.7032551816523;842.372432537751;882.6998820401094;885.7165706973835;890.5992303463468;27108.457830843272;29780.851095744816;29922.850385747934;30231;30530.084097476665;1041.9479026048666;1051.8790339110947;1065.8028264771042;1071.5124618298655;1197.8442802435802;12830.29433381173;12950.805737913939;14459.855401445851;15431.61420964485;15961.760573591406;634779;639931;639987;640049;640212.7989360023;52226;52881;52907.470925291025;53006.46993530093;53099.20351194735;1355.7017456159629;1398.7202559488144;1418.0782491380685;1418.5744276716944;1420.5312246958479;27780.30549236285;28164.577531336636;28218.576721349607;28761;28850.711492884802;46479.535204647524;47207.763961179975;47271.52728472809;47289.52710472851;47418;577957;578379;578431;578552;579529;21703.457413564156;22267.66598501056;24088.75911240865;24605.13882014121;24695.753042470064;41146.58853411428;41460.792696036326;41496.58503415048;41499.58500414957;41916.79041604894;34875.825620871736;34938.9518314451;35045.64954350424;35749;35863.10342241465;35033.64966350304;35053.824730876186;35908;35918.461223082195;35947.640523594426;34227.82886085554;34747.65252347545;34842.65157348495;34899.47650785291;34934;29771.553426698185;32143.678563214067;32207.677923221407;32242.35515289728;32243.35513289768;18784.812151878305;23828.523429530964;24118.638220426357;24362.634560481252;26478 09:43:58 INFO - PROCESS | 1640 | |2;jquery.html;335.63248243173723;387.60270722509443;395.98627513565276;416.0846138495311;420.4240190938415;18984.247284143792;19855.503612409662;20397.49006274844;20465.897670511644;20750.688739668898;11821;12167.634970950863;12169.817452738216;12193.390330483462;19026.238950441966;5114.386273647158;5372.194170874381;5375.623706340558;5375.6774593524315;5379.488948549901;5325.227841962933;5467.343918729758;5925.703714814264;5991.460768530831;6004.489618382443;5963.4334738199805;5965;5966.433188847052;5969.104634304867;5981.521478281748;5661.886762264752;5814.680192589406;5871.148683440899;5941.10883367494;5944.851378715523;5081.009203205376;5264.578833693296;5274.4198138204765;5277.815276465343;5282.339707536558;24523.896424660932;24895.751042489705;25414.745852541422;25562.36094097644;25896.74103258962;2628.4086080631896;2643.4977354302623;2940.7647388208893;2967.139529536432;3049.1767222849708;7717.768466945944;10229.744256393575;10601;10602.363858168457;10619.36283822973;1844.1609067874085;1860.6464771693336;1861.385742704904;1865.2445759467437;1874.175362840346;121521;124201.13698294533;134696;138658;139022;11530.481128349244;11635.825462617899;11733;11743.181636407371;11935.701607459709;15397.84602153964;15409.229538523028;15411.691766164615;15415.845841541666;15494.535163944875;8219.342452603778;8366.832663346699;8370.748877533684;8421;8498.915010849936;7994.682206884078;8011.409375962772;8030.678772849137;8171.387145964077;8179.79550511242;4702.35417603836;4705.6470764692785;4730.479647238801;4734.55021772931;4847.224444088999;1738.913054347303;1952.912118954622;1964.8231659150745;2002.929897453582;2007.4078146946686;840.9369297302727;906.7758525989992;992.9106380425796;993.2203220471895;1009.1573536097441;20530.897345513476;20574.79425205759;20742.792572074388;20828.89585552093;21020.137297496112;1003.1573478278282;1015.7962814065356;1026.8767747870304;1034.3276870034538;1036.3988886445843;10396.896031039743;10854.403007834488;10896.964788345094;10934.726631834108;10936.289141205796;472905.89658723556;528998.7100128949;686100.5694971493;689028;748334;107313;148505;153944;162267;164453;987.7086259553305;988.545269176187;1031.0359813574332;1032.912202462784;1037.5382954585136;17059.91470042642;17949.923004619766;18607.81392186115;18681.972491513097;18742.344017959305;58741.4125858753;58961;60879.69560152171;61119.69440152771;61213;77992.61003694945;85203;87894.80262993477;89181;91293.97883116492;6391.424771770563;6702.36327548882;8114.83770324602;8319.459235149938;8480.321574274167;10199.898001019896;12493.375331233583;12609.30648814324;12665.81001284963;12822.807657884952;9967.202623789934;10373.740656483647;10735.46322683847;10906.672799816019;11034;10641.787164256826;10805.621803236849;10898.836517452402;10986.450677465935;11005.17461190398;10736.570537178737;10736.73158171052;11220.438978051228;11255;11352.524171857605;6119.724612392542;7119.608421536658;7354.301341372561;7539.660715267716;7643.159252482112;8844.380893337404;10359.689209323733;10563.419011954416;10963.83554246671;11043.668689939315 09:43:58 INFO - PROCESS | 1640 | |3;mootools.html;1416.2281556551684;1427.2435609127162;1428.685689148387;1438.288047416529;1439.3163247457453;6993.510454268203;7659.846803063935;8142.674293028272;8168.50988940664;8173.223543763348;7192.820179495515;7481.139668938074;7760.805979850506;7894.302724914142;8007.599620019005;2238.563480121377;2274.9886250568807;2276.3398614401804;2277.8519396239276;2279.384566167134;2189.1462329691435;2267.8979445924974;2282.6461898405773;2298.7241531016216;2304.6888670029584;1988.7911769264235;2062.7730949595534;2063.7007633893077;2201.5156665533636;2270.0806173499677;2258.785415385544;2277.89749461273;2280.1107568048474;2281.908723651052;2284.942876428086;2226.677131815894;2251.3696165073807;2253.887305634728;2262.988685056581;2265.694131292271;8043;8056.637451314705;8135.227153420416;8145.429819912609;8154.388420868399;4902.950970490285;5057.013882292954;5062.620303477254;5074.822381216677;5084.593232541388;2936.5154749466315;3266.8039917604783;3405.6934875861043;3416.7437442191685;3467.219875528011;1017.2319898476603;1123.230587047875;1188.1089183112665;1206.4329765010432;1248.8813562711528;10347.482625868752;10457;10509.789804203949;10527.999840015264;10528.631497897613;4306.246406878784;4463.598276155161;4536.364908912688;4547.818087276538;4549.158405694957;9159.175674189355;9166.724998250063;9173.266138708887;9196.218321442619;9224.123708247706;5001.624878134155;5066.265391518209;5076.746162691925;5080.161773307437;5086.033653605803;4546.954530454719;4590.334401511762;4615.469221039556;4645.744484053342;4647.326137710081;3691.5570131583845;3752.493413389212;3767.1335592813807;3794.5067141271456;3810.733248672622;1211.4669545399995;1214.6173955204083;1219.7072702551325;1230.194222784078;1235.178606226849;733.2154594583851;773.9419543534258;776.2547953964257;781.9296263336327;805.1103530598721;5225.387864612705;5258.553022993012;5375.865603359869;5594.748236329375;5613.410591887875;558.6620094842643;585.631052436962;585.6369051188291;586.8004878341299;613.6164896939413;3556.822158892045;3694.8706795262037;3799.1262009737916;3811.3520701480816;3817.446470261852;9248.861267081;9747.074027967332;9751.3661611995;9773.609055637837;9779.755506112404;9472.479013654316;9513.714588562354;9572.56923438447;9714.854277185706;9756.12194902448;7564.697412103455;7596.962015189889;7653.655585498666;7680.654370553339;7684;5340.679559226459;5396.109641909198;5421.512063914267;5443.374011988513;5475.835724928259;8882.022977472601;8981.236594889508;9027.684031058881;9038.32212584072;9039.909600903908;7592.278733520307;8878.378513503993;9143.817123657356;9175.908240917772;9240.168384845127;5901.232839730807;6885.414739747178;6981.371676549135;6983.127109111361;6983.406410454965;7674.194209608134;7714.6914123434435;7718.266764657348;7726.304632583095;7747.147813740597;6795.422389096784;6807.115075040213;6861.4853885957755;6906.136732908387;7020.22777494465;6720.092787473628;6723;6785.796426107225;6868.965655171892;6887.20797108339;4926.531979462089;6252.124702541557;6320.4311611955145;6325.77859774924;6339.175907132043;4845.709257444423;4915.26271059344;4924.4090709115135;4935.259711043373;5085.77114029877;3750.3249415105543;4926.5073492650445;4988.825391111293;5023.422306434807;5079.085764562415 09:43:58 INFO - PROCESS | 1640 | |4;prototype.html;352.66496828013385;360.27404779369573;363.5092624956308;372.22205590316236;376.73063759412014;4170.416141740159;5215.58275337973;5269.868253293665;5319.973400133003;5411.729413529318;4315.590018948203;5095.668781529198;5172.224166375046;5233.64485981308;5237.7381130943495;4183.267928112568;4215.683823713219;4223.429836972008;4227.640650544708;4229.217594744982;3710.9257814843822;3985.2627263956115;4080.9591904080876;4227.598378154071;4230.598093181143;4137.462129923106;4156.334986402177;4173.56177601352;4173.916521669565;4179.519355274152;3645.562532496091;4123.649489793371;4161.91676166475;4165.83336666533;4184.832606695729;2914.446255211524;3228.370467758788;3523.7448033258734;3677.4667673187237;3679.920844722723;4529.433820772403;4936.358273424467;5221.425643179247;5236.895262094775;5249.816256431045;4494.842680506166;4759.643026772971;4763.880902977418;4784.258439941815;4803.3995750531185;5629.9437005629825;5645.32256129263;5660.971695141498;5663.603547751659;5676.290463692038;3421.7091547218515;3508.4211105167874;3824.579296277407;3857.4792403025795;4033.556308806029;5377.46225377463;5564.777408903639;5874.356539557216;5887.381824908366;5899.498542623839;3313.5858017747782;3419.3845107880325;3870.148567315186;3873.8063096845044;3885.4846609822193;3666.6883314917995;3908.7654740715648;3949.3483575210344;3951.3009405955713;3955.4462375267485;4529.796159172845;4584.931226031612;4585.243434833215;4588.174128656874;4601.470830854495;4738.573528382462;5618.353889302783;5631.577631677641;5676.403977582377;5736.913946290809;1108.7893300273083;1170.7473003885761;1174.9118816088828;1230.5877531027206;1252.599168266146;818.926296633294;847.8516259654535;853.5134973065293;869.3566760597113;879.0286733159894;4215.473065866767;4230.450041494586;4287.506936702319;4757.833475828329;5036.294918711384;5461.535769459561;5503.807366742145;5571.690652696613;5606.187102870061;5638.182463542846;4255.468066491689;4962.131626965267;4966.932109596381;5344.942197254008;5369.060414427459;2895.2038189498057;2904.361040571071;2909.3744844858456;2914.519104347759;2922.444735500248;4628.190066738307;4646.535346465326;4657.417822772153;5256.500632439912;5717.285339332583;4858.951410485921;4936.160852654985;5337.439568845215;5583.213371721092;5658.613639658228;5658.575606829504;5706;5720.313562372458;5721.5708821838525;5753.784792854697;4205.873823785291;4738.715677059387;4775.355327030807;5374.220737992891;5474.822913073708;4896.583790377716;4968;5075.847724568269;5224.817131400383;5312;4665.4168228971375;5224.346956630421;5389.218563308376;5573.7180448496265;5805.41945805433;3429.622741498383;3637.7623914715396;3641.7632853865475;3646.9817650911577;3655.3603119453455;5028.974855125701;5481.671099734029;5539.473749993744;5572.192032155396;5592.3848376677715;4488.730676159441;4594.931076033795;5212.348456442945;5277.050130976461;5315.01672190646;4385.144896745104;4460.977695111504;4705.541282202528;4885.497701995578;4965.702057876539;4715.6463265255925;5168.819091331786;5308.57531397495;5312.282841816305;5366.785328586813;4938.604911606991;4996.325496057985;5055.912978709595;5282.022825777243;5620.747066382104;5327.813526526553;5470.835874923759;5514.200440936122;5519.862003449946;5541.861453463695 09:43:58 INFO - PROCESS | 1640 | |5;yui.html;703.9472039597032;704.66176235407;706.1420374245295;799.892014578032;829.0797215091247;14576;15478.922605386986;15489.92255038726;15511.146886921215;15536.456224032161;10649.893501064988;14010.719785604282;14166.079204851678;14207;14231.857681423182;871.5250188647186;895.995520022399;897.6364572348209;900.8063266397724;905.6965916417975;898.7932775461647;901.4456109492647;901.8106197698459;903.8960519540238;904.9999750275949;937.3298091864316;937.9718608441758;939.8825146856643;942.3639043645546;942.8821397325335;802.7712102050888;905.1762895764822;929.9163075323254;932.4917919733762;934.0799312677008;676.1885737115481;693.6323748413349;698.0262533765369;700.2577268095835;700.5586480517293;15561.299741511659;15561.688766224612;15565.143917084555;15586.922065389714;15587.376504939788;12749.42627581761;13526.256055916921;13991.23048232357;14034.649133771738;14041.578752637437;11776;11826.645200643909;11841.052715782716;11954.521819127225;12006.819897701542;3604.098975256186;3611.205534782344;3649.9635003649887;3658.743887927846;3718.7954662493553;99530.50234748852;105684;109063;109133.45433272782;109307;10147.594096236215;10195.949020254851;10212.387256764618;10318.948405257926;10593.417362045007;21134.260300889397;21822.236221732164;21962.47431545393;22084.558308833733;22160.113595456;7550.924490755132;7560.9621951889885;7573.2805383488485;7620.428467864932;7786.727464538714;4199.076203235344;4285.671769129297;4306.267934451088;4322.3516472528745;4407.096545208225;3404.506346579732;3435.914102147466;3462.255615042778;3508.52634894286;3514.68367846895;624.5596854217765;626.6083697688945;646.7542333913083;678.5996262205323;691.4261163234503;666.9499787515956;683.040328338685;707.3633729643362;721.6932803558467;745.3552676934413;9472.668456603986;9577;9693.951530242304;10151.847722284172;10434.42610656406;637.6715991264502;679.6805501414327;686.306830101601;687.9036934829128;692.1555702043499;4615.9076818463445;4939.185034469344;4952.62855285855;4953.628477864175;4967.3542439482635;89164;94207.11585768247;103152.96847031584;103804.48097759463;103956;61626;88585;90094.54952725195;90793;91949.0805091954;62850.685746570976;64504.67747661232;65435.018474722914;76592.61703691445;79964;10340.224483163642;10601.946990265;10752.40861752611;10814.675559733221;10852.67441976742;42440.78779606206;49699;50269;52777.47222527879;54047.72976135094;66820.66589667021;68823.3117668837;69967.65016174887;70301.64849175721;71159.64420177866;12367.567135150226;12368.567100151451;12550.372481376076;12679.619611411672;12731.55439559611;14009.509667161601;14200.78698819498;14231.319839344587;14276.71446571042;14372.784408234094;13080.476780928657;13198.538051168163;13284.468621255042;13427.127236729972;13520;10750.408727519749;13187.274699891597;13462.192268463923;13645.385957631732;13825.446982120602;12927.482900683868;13070.150440221736;13346.532871349456;13613.610616200913;13779;10538.947305263424;11049.944750276198;11268.267562608404;11332;11380.203385763249;8633.39566230358;10334.638287659896;10557.841632375366;10675.51960161779;10744.892551074388 09:43:58 INFO - PROCESS | 1640 | __end_tp_report 09:43:58 INFO - PROCESS | 1640 | __start_cc_report 09:43:58 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,3066 09:43:58 INFO - PROCESS | 1640 | __end_cc_report 09:43:58 INFO - PROCESS | 1640 | __startTimestamp1483724638941__endTimestamp 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | ------- Summary: start ------- 09:43:58 INFO - PROCESS | 1640 | Number of tests: 6 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5717.70 Median:5924.59 stddev:655.04 (11.1%) stddev-sans-first:630.00 09:43:58 INFO - PROCESS | 1640 | Values: 3343.1 3345.3 3364.9 3380.2 3419.1 4288.0 5577.4 5664.1 5669.6 5698.3 5596.5 5679.5 5738.4 5750.2 5764.9 5087.6 5120.9 5146.3 5165.1 5208.6 5198.2 5267.9 5278.0 5280.7 5338.8 5106.7 5143.1 5219.5 5221.7 5277.7 5140.2 5209.0 5215.6 5226.3 5265.1 5164.0 5168.5 5201.0 5226.2 5249.2 5705.0 5731.9 5738.9 5763.5 5789.6 6124.5 6147.8 6158.8 6166.3 6283.7 6254.0 6273.3 6292.6 6316.4 6336.1 5811.1 5811.2 5818.7 5820.5 5849.9 6129.8 6230.5 6293.2 6297.0 6298.6 4758.0 5079.3 6063.8 6090.7 6096.0 6107.6 6176.1 6259.3 6259.4 6273.6 6102.5 6102.6 6104.2 6133.8 6265.6 6254.1 6257.6 6265.3 6271.8 6273.6 4855.1 4884.2 4899.5 4916.7 4986.0 4407.3 4410.9 4438.9 4444.8 4463.6 5814.5 5818.4 5833.1 5837.0 5884.8 4611.7 5998.3 6164.8 6307.6 6322.2 5722.7 5757.7 5759.8 5807.2 5823.5 5366.0 5399.8 5404.7 5410.0 5431.5 6218.9 6328.6 6355.5 6355.5 6367.6 6195.0 6196.8 6233.3 6261.4 6274.6 6299.8 6311.2 6323.8 6325.2 6328.7 6156.2 6162.8 6199.6 6200.0 6206.2 6117.4 6171.5 6198.5 6219.0 6273.7 6113.0 6158.8 6163.5 6168.0 6179.6 5979.2 6018.2 6119.5 6129.6 6166.4 5874.3 5960.0 5969.6 5977.6 5986.2 6084.9 6097.3 6100.8 6105.4 6113.5 5806.9 5858.3 5914.4 5924.6 5944.9 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:67599.28 Median:34916.74 stddev:149373.49 (427.8%) stddev-sans-first:149707.55 09:43:58 INFO - PROCESS | 1640 | Values: 492.3 495.1 497.5 506.0 507.5 34683.0 39036.8 39914.6 40938.8 42714.1 41629.8 42031.6 42115.4 42268.8 42391.8 8450.0 8677.0 9233.3 9453.9 9473.7 11343.7 11655.5 11662.5 11830.8 11858.7 11383.0 11457.9 11472.1 11476.2 11513.6 11349.7 11413.2 11435.1 11435.8 11440.5 11301.4 11369.9 11374.7 11378.8 11391.3 39554.4 39588.4 39607.4 39640.6 39681.4 3969.2 3972.6 3986.1 4001.1 4055.0 29508.7 30004.7 30114.5 30378.5 31145.1 2988.1 3088.6 3166.6 3181.6 3280.0 434870.0 437998.0 438394.0 439664.0 442293.0 54120.7 54417.7 54429.5 54442.9 54580.7 56831.0 56850.7 56971.7 57011.7 57084.0 14618.4 15162.2 15166.6 15174.1 15181.2 10506.5 10771.7 11550.4 11660.8 11731.9 17312.7 17355.4 17360.8 17362.0 17453.0 6403.6 7043.8 7245.0 7259.9 7323.8 732.7 842.4 882.7 885.7 890.6 27108.5 29780.9 29922.9 30231.0 30530.1 1041.9 1051.9 1065.8 1071.5 1197.8 12830.3 12950.8 14459.9 15431.6 15961.8 634779.0 639931.0 639987.0 640049.0 640212.8 52226.0 52881.0 52907.5 53006.5 53099.2 1355.7 1398.7 1418.1 1418.6 1420.5 27780.3 28164.6 28218.6 28761.0 28850.7 46479.5 47207.8 47271.5 47289.5 47418.0 577957.0 578379.0 578431.0 578552.0 579529.0 21703.5 22267.7 24088.8 24605.1 24695.8 41146.6 41460.8 41496.6 41499.6 41916.8 34875.8 34939.0 35045.6 35749.0 35863.1 35033.6 35053.8 35908.0 35918.5 35947.6 34227.8 34747.7 34842.7 34899.5 34934.0 29771.6 32143.7 32207.7 32242.4 32243.4 18784.8 23828.5 24118.6 24362.6 26478.0 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:36813.71 Median:22772.02 stddev:106656.70 (468.4%) stddev-sans-first:106920.73 09:43:58 INFO - PROCESS | 1640 | Values: 335.6 387.6 396.0 416.1 420.4 18984.2 19855.5 20397.5 20465.9 20750.7 11821.0 12167.6 12169.8 12193.4 19026.2 5114.4 5372.2 5375.6 5375.7 5379.5 5325.2 5467.3 5925.7 5991.5 6004.5 5963.4 5965.0 5966.4 5969.1 5981.5 5661.9 5814.7 5871.1 5941.1 5944.9 5081.0 5264.6 5274.4 5277.8 5282.3 24523.9 24895.8 25414.7 25562.4 25896.7 2628.4 2643.5 2940.8 2967.1 3049.2 7717.8 10229.7 10601.0 10602.4 10619.4 1844.2 1860.6 1861.4 1865.2 1874.2 121521.0 124201.1 134696.0 138658.0 139022.0 11530.5 11635.8 11733.0 11743.2 11935.7 15397.8 15409.2 15411.7 15415.8 15494.5 8219.3 8366.8 8370.7 8421.0 8498.9 7994.7 8011.4 8030.7 8171.4 8179.8 4702.4 4705.6 4730.5 4734.6 4847.2 1738.9 1952.9 1964.8 2002.9 2007.4 840.9 906.8 992.9 993.2 1009.2 20530.9 20574.8 20742.8 20828.9 21020.1 1003.2 1015.8 1026.9 1034.3 1036.4 10396.9 10854.4 10897.0 10934.7 10936.3 472905.9 528998.7 686100.6 689028.0 748334.0 107313.0 148505.0 153944.0 162267.0 164453.0 987.7 988.5 1031.0 1032.9 1037.5 17059.9 17949.9 18607.8 18682.0 18742.3 58741.4 58961.0 60879.7 61119.7 61213.0 77992.6 85203.0 87894.8 89181.0 91294.0 6391.4 6702.4 8114.8 8319.5 8480.3 10199.9 12493.4 12609.3 12665.8 12822.8 9967.2 10373.7 10735.5 10906.7 11034.0 10641.8 10805.6 10898.8 10986.5 11005.2 10736.6 10736.7 11220.4 11255.0 11352.5 6119.7 7119.6 7354.3 7539.7 7643.2 8844.4 10359.7 10563.4 10963.8 11043.7 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5249.90 Median:5155.71 stddev:2867.46 (55.6%) stddev-sans-first:2861.02 09:43:58 INFO - PROCESS | 1640 | Values: 1416.2 1427.2 1428.7 1438.3 1439.3 6993.5 7659.8 8142.7 8168.5 8173.2 7192.8 7481.1 7760.8 7894.3 8007.6 2238.6 2275.0 2276.3 2277.9 2279.4 2189.1 2267.9 2282.6 2298.7 2304.7 1988.8 2062.8 2063.7 2201.5 2270.1 2258.8 2277.9 2280.1 2281.9 2284.9 2226.7 2251.4 2253.9 2263.0 2265.7 8043.0 8056.6 8135.2 8145.4 8154.4 4903.0 5057.0 5062.6 5074.8 5084.6 2936.5 3266.8 3405.7 3416.7 3467.2 1017.2 1123.2 1188.1 1206.4 1248.9 10347.5 10457.0 10509.8 10528.0 10528.6 4306.2 4463.6 4536.4 4547.8 4549.2 9159.2 9166.7 9173.3 9196.2 9224.1 5001.6 5066.3 5076.7 5080.2 5086.0 4547.0 4590.3 4615.5 4645.7 4647.3 3691.6 3752.5 3767.1 3794.5 3810.7 1211.5 1214.6 1219.7 1230.2 1235.2 733.2 773.9 776.3 781.9 805.1 5225.4 5258.6 5375.9 5594.7 5613.4 558.7 585.6 585.6 586.8 613.6 3556.8 3694.9 3799.1 3811.4 3817.4 9248.9 9747.1 9751.4 9773.6 9779.8 9472.5 9513.7 9572.6 9714.9 9756.1 7564.7 7597.0 7653.7 7680.7 7684.0 5340.7 5396.1 5421.5 5443.4 5475.8 8882.0 8981.2 9027.7 9038.3 9039.9 7592.3 8878.4 9143.8 9175.9 9240.2 5901.2 6885.4 6981.4 6983.1 6983.4 7674.2 7714.7 7718.3 7726.3 7747.1 6795.4 6807.1 6861.5 6906.1 7020.2 6720.1 6723.0 6785.8 6869.0 6887.2 4926.5 6252.1 6320.4 6325.8 6339.2 4845.7 4915.3 4924.4 4935.3 5085.8 3750.3 4926.5 4988.8 5023.4 5079.1 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4407.01 Median:4793.83 stddev:1323.11 (27.6%) stddev-sans-first:1291.35 09:43:58 INFO - PROCESS | 1640 | Values: 352.7 360.3 363.5 372.2 376.7 4170.4 5215.6 5269.9 5320.0 5411.7 4315.6 5095.7 5172.2 5233.6 5237.7 4183.3 4215.7 4223.4 4227.6 4229.2 3710.9 3985.3 4081.0 4227.6 4230.6 4137.5 4156.3 4173.6 4173.9 4179.5 3645.6 4123.6 4161.9 4165.8 4184.8 2914.4 3228.4 3523.7 3677.5 3679.9 4529.4 4936.4 5221.4 5236.9 5249.8 4494.8 4759.6 4763.9 4784.3 4803.4 5629.9 5645.3 5661.0 5663.6 5676.3 3421.7 3508.4 3824.6 3857.5 4033.6 5377.5 5564.8 5874.4 5887.4 5899.5 3313.6 3419.4 3870.1 3873.8 3885.5 3666.7 3908.8 3949.3 3951.3 3955.4 4529.8 4584.9 4585.2 4588.2 4601.5 4738.6 5618.4 5631.6 5676.4 5736.9 1108.8 1170.7 1174.9 1230.6 1252.6 818.9 847.9 853.5 869.4 879.0 4215.5 4230.5 4287.5 4757.8 5036.3 5461.5 5503.8 5571.7 5606.2 5638.2 4255.5 4962.1 4966.9 5344.9 5369.1 2895.2 2904.4 2909.4 2914.5 2922.4 4628.2 4646.5 4657.4 5256.5 5717.3 4859.0 4936.2 5337.4 5583.2 5658.6 5658.6 5706.0 5720.3 5721.6 5753.8 4205.9 4738.7 4775.4 5374.2 5474.8 4896.6 4968.0 5075.8 5224.8 5312.0 4665.4 5224.3 5389.2 5573.7 5805.4 3429.6 3637.8 3641.8 3647.0 3655.4 5029.0 5481.7 5539.5 5572.2 5592.4 4488.7 4594.9 5212.3 5277.1 5315.0 4385.1 4461.0 4705.5 4885.5 4965.7 4715.6 5168.8 5308.6 5312.3 5366.8 4938.6 4996.3 5055.9 5282.0 5620.7 5327.8 5470.8 5514.2 5519.9 5541.9 09:43:58 INFO - PROCESS | 1640 | 09:43:58 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20011.69 Median:3654.35 stddev:28622.60 (783.2%) stddev-sans-first:28666.18 09:43:58 INFO - PROCESS | 1640 | Values: 703.9 704.7 706.1 799.9 829.1 14576.0 15478.9 15489.9 15511.1 15536.5 10649.9 14010.7 14166.1 14207.0 14231.9 871.5 896.0 897.6 900.8 905.7 898.8 901.4 901.8 903.9 905.0 937.3 938.0 939.9 942.4 942.9 802.8 905.2 929.9 932.5 934.1 676.2 693.6 698.0 700.3 700.6 15561.3 15561.7 15565.1 15586.9 15587.4 12749.4 13526.3 13991.2 14034.6 14041.6 11776.0 11826.6 11841.1 11954.5 12006.8 3604.1 3611.2 3650.0 3658.7 3718.8 99530.5 105684.0 109063.0 109133.5 109307.0 10147.6 10195.9 10212.4 10318.9 10593.4 21134.3 21822.2 21962.5 22084.6 22160.1 7550.9 7561.0 7573.3 7620.4 7786.7 4199.1 4285.7 4306.3 4322.4 4407.1 3404.5 3435.9 3462.3 3508.5 3514.7 624.6 626.6 646.8 678.6 691.4 666.9 683.0 707.4 721.7 745.4 9472.7 9577.0 9694.0 10151.8 10434.4 637.7 679.7 686.3 687.9 692.2 4615.9 4939.2 4952.6 4953.6 4967.4 89164.0 94207.1 103153.0 103804.5 103956.0 61626.0 88585.0 90094.5 90793.0 91949.1 62850.7 64504.7 65435.0 76592.6 79964.0 10340.2 10601.9 10752.4 10814.7 10852.7 42440.8 49699.0 50269.0 52777.5 54047.7 66820.7 68823.3 69967.7 70301.6 71159.6 12367.6 12368.6 12550.4 12679.6 12731.6 14009.5 14200.8 14231.3 14276.7 14372.8 13080.5 13198.5 13284.5 13427.1 13520.0 10750.4 13187.3 13462.2 13645.4 13825.4 12927.5 13070.2 13346.5 13613.6 13779.0 10538.9 11049.9 11268.3 11332.0 11380.2 8633.4 10334.6 10557.8 10675.5 10744.9 09:43:58 INFO - PROCESS | 1640 | -------- Summary: end -------- 09:43:58 INFO - PROCESS | 1640 | 09:43:59 INFO - TEST-INFO | 1640: exit 0 09:43:59 INFO - TEST-OK | dromaeo_css | took 1118482ms 09:43:59 INFO - TEST-START | kraken 09:43:59 INFO - operating with platform_type : mac_ 09:43:59 INFO - Initialising browser for kraken test... 09:43:59 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpHinLMe/profile http://localhost:49245/getInfo.html) 09:44:00 INFO - PROCESS | 1651 | 1483724640054 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:44:01 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:44:01 INFO - PROCESS | 1651 | colorDepth:24 09:44:01 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:44:01 INFO - PROCESS | 1651 | __metrics 09:44:01 INFO - PROCESS | 1651 | 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" 09:44:01 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:02 INFO - TEST-INFO | 1651: exit 0 09:44:02 INFO - Browser initialized. 09:44:02 INFO - Running cycle 1/1 for kraken test... 09:44:02 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpHinLMe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:14 INFO - PROCESS | 1652 | RSS: Main: 234639360 09:44:14 INFO - PROCESS | 1652 | 09:44:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 09:44:18 INFO - PROCESS | 1652 | RSS: Main: 286306304 09:44:18 INFO - PROCESS | 1652 | 09:44:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 09:44:21 INFO - PROCESS | 1652 | RSS: Main: 285708288 09:44:21 INFO - PROCESS | 1652 | 09:44:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 09:44:25 INFO - PROCESS | 1652 | RSS: Main: 379785216 09:44:25 INFO - PROCESS | 1652 | 09:44:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 09:44:27 INFO - PROCESS | 1652 | RSS: Main: 283635712 09:44:27 INFO - PROCESS | 1652 | 09:44:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:44:30 INFO - PROCESS | 1652 | RSS: Main: 285900800 09:44:30 INFO - PROCESS | 1652 | 09:44:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 09:44:33 INFO - PROCESS | 1652 | RSS: Main: 326987776 09:44:33 INFO - PROCESS | 1652 | 09:44:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 09:44:37 INFO - PROCESS | 1652 | RSS: Main: 328548352 09:44:37 INFO - PROCESS | 1652 | 09:44:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 09:44:41 INFO - PROCESS | 1652 | RSS: Main: 326643712 09:44:41 INFO - PROCESS | 1652 | 09:44:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:44:43 INFO - PROCESS | 1652 | RSS: Main: 302190592 09:44:43 INFO - PROCESS | 1652 | 09:44:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:44:45 INFO - PROCESS | 1652 | RSS: Main: 301694976 09:44:45 INFO - PROCESS | 1652 | 09:44:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:44:48 INFO - PROCESS | 1652 | RSS: Main: 303710208 09:44:48 INFO - PROCESS | 1652 | 09:44:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:44:52 INFO - PROCESS | 1652 | RSS: Main: 325169152 09:44:52 INFO - PROCESS | 1652 | 09:44:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:44:55 INFO - PROCESS | 1652 | RSS: Main: 319033344 09:44:55 INFO - PROCESS | 1652 | 09:44:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 09:44:58 INFO - PROCESS | 1652 | RSS: Main: 362786816 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | __start_tp_report 09:44:58 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:44:58 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:44:58 INFO - PROCESS | 1652 | |i|pagename|runs| 09:44:58 INFO - PROCESS | 1652 | |0;ai-astar;113;112;113;111;112;113;112;113;113;111 09:44:58 INFO - PROCESS | 1652 | |1;audio-beat-detection;139;107;108;142;109;104;106;108;126;110 09:44:58 INFO - PROCESS | 1652 | |2;audio-dft;186;183;180;183;181;183;182;186;182;178 09:44:58 INFO - PROCESS | 1652 | |3;audio-fft;81;58;58;86;61;58;58;58;76;74 09:44:58 INFO - PROCESS | 1652 | |4;audio-oscillator;86;69;86;68;68;69;82;68;67;84 09:44:58 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;131;86;84;86;83;88;86;88;84 09:44:58 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;95;96;96;117;170;95;95;97;96 09:44:58 INFO - PROCESS | 1652 | |7;imaging-desaturate;126;133;85;85;84;87;86;89;85;85 09:44:58 INFO - PROCESS | 1652 | |8;json-parse-financial;56;54;55;55;56;57;55;56;56;58 09:44:58 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;68;67;80;77;74;71;71;71;71;72 09:44:58 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;86;92;86;86;86;76;85;107;106;109 09:44:58 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;141;140;145;138;141;139;139;138;183;181 09:44:58 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;176;178;176;177;176;176;177;178;175;175 09:44:58 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;69;80;79;89;86;76;69;67;66;67 09:44:58 INFO - PROCESS | 1652 | __end_tp_report 09:44:58 INFO - PROCESS | 1652 | __start_cc_report 09:44:58 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2018 09:44:58 INFO - PROCESS | 1652 | __end_cc_report 09:44:58 INFO - PROCESS | 1652 | __startTimestamp1483724698067__endTimestamp 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:44:58 INFO - PROCESS | 1652 | Number of tests: 14 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.83 09:44:58 INFO - PROCESS | 1652 | Values: 113.0 112.0 113.0 111.0 112.0 113.0 112.0 113.0 113.0 111.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:115.90 Median:109.50 stddev:14.31 (13.1%) stddev-sans-first:12.50 09:44:58 INFO - PROCESS | 1652 | Values: 139.0 107.0 108.0 142.0 109.0 104.0 106.0 108.0 126.0 110.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.00 stddev:2.46 (1.3%) stddev-sans-first:2.24 09:44:58 INFO - PROCESS | 1652 | Values: 186.0 183.0 180.0 183.0 181.0 183.0 182.0 186.0 182.0 178.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.80 Median:67.50 stddev:11.19 (16.6%) stddev-sans-first:10.63 09:44:58 INFO - PROCESS | 1652 | Values: 81.0 58.0 58.0 86.0 61.0 58.0 58.0 58.0 76.0 74.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:75.50 stddev:8.53 (11.3%) stddev-sans-first:8.00 09:44:58 INFO - PROCESS | 1652 | Values: 86.0 69.0 86.0 68.0 68.0 69.0 82.0 68.0 67.0 84.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:86.00 stddev:14.36 (16.7%) stddev-sans-first:15.22 09:44:58 INFO - PROCESS | 1652 | Values: 89.0 131.0 86.0 84.0 86.0 83.0 88.0 86.0 88.0 84.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:105.20 Median:95.50 stddev:23.75 (24.9%) stddev-sans-first:24.90 09:44:58 INFO - PROCESS | 1652 | Values: 95.0 95.0 96.0 96.0 117.0 170.0 95.0 95.0 97.0 96.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:85.00 stddev:18.57 (21.9%) stddev-sans-first:15.82 09:44:58 INFO - PROCESS | 1652 | Values: 126.0 133.0 85.0 85.0 84.0 87.0 86.0 89.0 85.0 85.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.14 (2.0%) stddev-sans-first:1.20 09:44:58 INFO - PROCESS | 1652 | Values: 56.0 54.0 55.0 55.0 56.0 57.0 55.0 56.0 56.0 58.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:71.50 stddev:3.91 (5.5%) stddev-sans-first:3.84 09:44:58 INFO - PROCESS | 1652 | Values: 68.0 67.0 80.0 77.0 74.0 71.0 71.0 71.0 71.0 72.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:86.00 stddev:11.35 (13.2%) stddev-sans-first:11.83 09:44:58 INFO - PROCESS | 1652 | Values: 86.0 92.0 86.0 86.0 86.0 76.0 85.0 107.0 106.0 109.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:141.00 stddev:17.78 (12.6%) stddev-sans-first:18.65 09:44:58 INFO - PROCESS | 1652 | Values: 141.0 140.0 145.0 138.0 141.0 139.0 139.0 138.0 183.0 181.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:176.50 stddev:1.07 (0.6%) stddev-sans-first:1.13 09:44:58 INFO - PROCESS | 1652 | Values: 176.0 178.0 176.0 177.0 176.0 176.0 177.0 178.0 175.0 175.0 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:77.50 stddev:8.43 (10.9%) stddev-sans-first:8.68 09:44:58 INFO - PROCESS | 1652 | Values: 69.0 80.0 79.0 89.0 86.0 76.0 69.0 67.0 66.0 67.0 09:44:58 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:44:58 INFO - PROCESS | 1652 | 09:44:58 INFO - TEST-INFO | 1652: exit 0 09:44:58 INFO - TEST-OK | kraken | took 59393ms 09:44:58 INFO - SUITE-END | took 1177s 09:44:59 INFO - Completed test suite (00:19:38) 09:44:59 INFO - javascript benchmark 09:44:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.063942096213, 3345.2807646356027, 3364.8990530284086, 3380.154961259685, 3419.1281223288056, 4287.957120428795, 5577.358603760569, 5664.065429204186, 5669.574781891359, 5698.2877140357405, 5596.468335508121, 5679.460451257134, 5738.3687794342695, 5750.166225897243, 5764.913526297109, 5087.6184286178495, 5120.948790512084, 5146.330976973007, 5165.121929272014, 5208.635395522315, 5198.194279886624, 5267.868303292429, 5278.0, 5280.683159010452, 5338.839834804962, 5106.744662766847, 5143.125668636303, 5219.5041471060185, 5221.660592061542, 5277.683338999673, 5140.1518749406305, 5208.984248071628, 5215.608829337777, 5226.2944502492055, 5265.052290587692, 5164.018836421105, 5168.483151684802, 5201.011807756552, 5226.189940559219, 5249.2388603652635, 5704.971475142639, 5731.914021289684, 5738.856528586777, 5763.452472015152, 5789.594728369016, 6124.540659450559, 6147.801178770142, 6158.753649854045, 6166.321704612534, 6283.748650053993, 6254.0, 6273.278572964122, 6292.590981586182, 6316.368363163647, 6336.081268216129, 5811.070228763378, 5811.215485909433, 5818.679972601548, 5820.505257053198, 5849.883002339972, 6129.785457508966, 6230.501559875199, 6293.244810622755, 6297.023961286008, 6298.559100862895, 4758.0, 5079.339685840817, 6063.757449701962, 6090.695465226721, 6096.0, 6107.61364822091, 6176.135341052256, 6259.311475737665, 6259.405356491126, 6273.592216505913, 6102.489251635897, 6102.587020209561, 6104.246950856165, 6133.775191399728, 6265.561410701207, 6254.124422580751, 6257.593256438408, 6265.27949285838, 6271.811845644546, 6273.560850740404, 4855.077535268288, 4884.242942343943, 4899.485554016848, 4916.70499770015, 4985.975070124626, 4407.294832826732, 4410.867673969786, 4438.911221775546, 4444.799984000728, 4463.598276155161, 5814.476697097281, 5818.359980402152, 5833.125031245348, 5837.0, 5884.7940322088525, 4611.677182597253, 5998.340182579913, 6164.7842325518395, 6307.621542707452, 6322.209723784527, 5722.713864306768, 5757.6833274169485, 5759.798407055733, 5807.186993820868, 5823.505002074787, 5366.0, 5399.811006614828, 5404.729763511808, 5409.972950135303, 5431.456854314616, 6218.875622487615, 6328.6202827830475, 6355.459785918156, 6355.491560675227, 6367.649779262185, 6195.039768835838, 6196.783112591038, 6233.345498722569, 6261.405166509175, 6274.592151510137, 6299.842503937438, 6311.2426508819235, 6323.841903952438, 6325.177726895474, 6328.746850126127, 6156.230471191101, 6162.753489860355, 6199.56603037783, 6200.038993955944, 6206.193194884636, 6117.3707050999965, 6171.506279497539, 6198.535109866868, 6219.036049412348, 6273.686315684289, 6112.989854784024, 6158.784442544489, 6163.537734670007, 6168.0, 6179.567430279837, 5979.222701048836, 6018.187544681412, 6119.541034422347, 6129.632222066691, 6166.352532984151, 5874.324452687995, 5959.9570075237525, 5969.582129251085, 5977.6114552553945, 5986.191864098291, 6084.90872636902, 6097.2683278006925, 6100.816975490565, 6105.419985101408, 6113.5414843886, 5806.854828629318, 5858.2677165354335, 5914.378990206139, 5924.585279030426, 5944.8811023780145], "value": 5676.9447031325535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.2616075886171, 495.07668198809233, 497.5472320188629, 506.0385267990816, 507.4544864270909, 34683.0, 39036.80481597591, 39914.60085399149, 40938.7953060235, 42714.14571708572, 41629.791851040776, 42031.57968420315, 42115.368269475985, 42268.78865605676, 42391.78804105975, 8449.95775021124, 8677.0, 9233.261339092891, 9453.905460945405, 9473.71578852632, 11343.659690209306, 11655.475503602316, 11662.475188616532, 11830.7633847323, 11858.703532411673, 11383.0, 11457.885421145807, 11472.082233421306, 11476.196666233367, 11513.597024104158, 11349.716257093598, 11413.201075924688, 11435.085193184566, 11435.828462573068, 11440.485178166919, 11301.434928253555, 11369.943150284196, 11374.658760237206, 11378.772424551546, 11391.259568128127, 39554.40668389977, 39588.40617390742, 39607.405888911686, 39640.60359396398, 39681.404778928336, 3969.2458432897656, 3972.5905137791324, 3986.1031267964768, 4001.099752555681, 4054.979725101385, 29508.70491295081, 30004.69995300041, 30114.54828177579, 30378.54432183519, 31145.065648030595, 2988.13344130202, 3088.5830412894197, 3166.6358368787655, 3181.5704879841155, 3280.0, 434870.0, 437998.0, 438394.0, 439664.0, 442293.0, 54120.72939635276, 54417.72791136019, 54429.45570544323, 54442.911141776945, 54580.72709636506, 56831.0, 56850.715746421, 56971.71514142403, 57011.71494142503, 57084.0, 14618.415263389557, 15162.24188790556, 15166.620834479227, 15174.089554626758, 15181.240937953058, 10506.527206275736, 10771.730706732395, 11550.364729939934, 11660.76678466426, 11731.941340293244, 17312.740308895376, 17355.392561260294, 17360.826391736173, 17362.0, 17453.040082795567, 6403.583767055126, 7043.759870459646, 7245.0, 7259.9274007259255, 7323.780286591411, 732.7032551816523, 842.372432537751, 882.6998820401094, 885.7165706973835, 890.5992303463468, 27108.457830843272, 29780.851095744816, 29922.850385747934, 30231.0, 30530.084097476665, 1041.9479026048666, 1051.8790339110947, 1065.8028264771042, 1071.5124618298655, 1197.8442802435802, 12830.29433381173, 12950.805737913939, 14459.855401445851, 15431.61420964485, 15961.760573591406, 634779.0, 639931.0, 639987.0, 640049.0, 640212.7989360023, 52226.0, 52881.0, 52907.470925291025, 53006.46993530093, 53099.20351194735, 1355.7017456159629, 1398.7202559488144, 1418.0782491380685, 1418.5744276716944, 1420.5312246958479, 27780.30549236285, 28164.577531336636, 28218.576721349607, 28761.0, 28850.711492884802, 46479.535204647524, 47207.763961179975, 47271.52728472809, 47289.52710472851, 47418.0, 577957.0, 578379.0, 578431.0, 578552.0, 579529.0, 21703.457413564156, 22267.66598501056, 24088.75911240865, 24605.13882014121, 24695.753042470064, 41146.58853411428, 41460.792696036326, 41496.58503415048, 41499.58500414957, 41916.79041604894, 34875.825620871736, 34938.9518314451, 35045.64954350424, 35749.0, 35863.10342241465, 35033.64966350304, 35053.824730876186, 35908.0, 35918.461223082195, 35947.640523594426, 34227.82886085554, 34747.65252347545, 34842.65157348495, 34899.47650785291, 34934.0, 29771.553426698185, 32143.678563214067, 32207.677923221407, 32242.35515289728, 32243.35513289768, 18784.812151878305, 23828.523429530964, 24118.638220426357, 24362.634560481252, 26478.0], "value": 19447.08303443423, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.63248243173723, 387.60270722509443, 395.98627513565276, 416.0846138495311, 420.4240190938415, 18984.247284143792, 19855.503612409662, 20397.49006274844, 20465.897670511644, 20750.688739668898, 11821.0, 12167.634970950863, 12169.817452738216, 12193.390330483462, 19026.238950441966, 5114.386273647158, 5372.194170874381, 5375.623706340558, 5375.6774593524315, 5379.488948549901, 5325.227841962933, 5467.343918729758, 5925.703714814264, 5991.460768530831, 6004.489618382443, 5963.4334738199805, 5965.0, 5966.433188847052, 5969.104634304867, 5981.521478281748, 5661.886762264752, 5814.680192589406, 5871.148683440899, 5941.10883367494, 5944.851378715523, 5081.009203205376, 5264.578833693296, 5274.4198138204765, 5277.815276465343, 5282.339707536558, 24523.896424660932, 24895.751042489705, 25414.745852541422, 25562.36094097644, 25896.74103258962, 2628.4086080631896, 2643.4977354302623, 2940.7647388208893, 2967.139529536432, 3049.1767222849708, 7717.768466945944, 10229.744256393575, 10601.0, 10602.363858168457, 10619.36283822973, 1844.1609067874085, 1860.6464771693336, 1861.385742704904, 1865.2445759467437, 1874.175362840346, 121521.0, 124201.13698294533, 134696.0, 138658.0, 139022.0, 11530.481128349244, 11635.825462617899, 11733.0, 11743.181636407371, 11935.701607459709, 15397.84602153964, 15409.229538523028, 15411.691766164615, 15415.845841541666, 15494.535163944875, 8219.342452603778, 8366.832663346699, 8370.748877533684, 8421.0, 8498.915010849936, 7994.682206884078, 8011.409375962772, 8030.678772849137, 8171.387145964077, 8179.79550511242, 4702.35417603836, 4705.6470764692785, 4730.479647238801, 4734.55021772931, 4847.224444088999, 1738.913054347303, 1952.912118954622, 1964.8231659150745, 2002.929897453582, 2007.4078146946686, 840.9369297302727, 906.7758525989992, 992.9106380425796, 993.2203220471895, 1009.1573536097441, 20530.897345513476, 20574.79425205759, 20742.792572074388, 20828.89585552093, 21020.137297496112, 1003.1573478278282, 1015.7962814065356, 1026.8767747870304, 1034.3276870034538, 1036.3988886445843, 10396.896031039743, 10854.403007834488, 10896.964788345094, 10934.726631834108, 10936.289141205796, 472905.89658723556, 528998.7100128949, 686100.5694971493, 689028.0, 748334.0, 107313.0, 148505.0, 153944.0, 162267.0, 164453.0, 987.7086259553305, 988.545269176187, 1031.0359813574332, 1032.912202462784, 1037.5382954585136, 17059.91470042642, 17949.923004619766, 18607.81392186115, 18681.972491513097, 18742.344017959305, 58741.4125858753, 58961.0, 60879.69560152171, 61119.69440152771, 61213.0, 77992.61003694945, 85203.0, 87894.80262993477, 89181.0, 91293.97883116492, 6391.424771770563, 6702.36327548882, 8114.83770324602, 8319.459235149938, 8480.321574274167, 10199.898001019896, 12493.375331233583, 12609.30648814324, 12665.81001284963, 12822.807657884952, 9967.202623789934, 10373.740656483647, 10735.46322683847, 10906.672799816019, 11034.0, 10641.787164256826, 10805.621803236849, 10898.836517452402, 10986.450677465935, 11005.17461190398, 10736.570537178737, 10736.73158171052, 11220.438978051228, 11255.0, 11352.524171857605, 6119.724612392542, 7119.608421536658, 7354.301341372561, 7539.660715267716, 7643.159252482112, 8844.380893337404, 10359.689209323733, 10563.419011954416, 10963.83554246671, 11043.668689939315], "value": 9557.93306313963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.2281556551684, 1427.2435609127162, 1428.685689148387, 1438.288047416529, 1439.3163247457453, 6993.510454268203, 7659.846803063935, 8142.674293028272, 8168.50988940664, 8173.223543763348, 7192.820179495515, 7481.139668938074, 7760.805979850506, 7894.302724914142, 8007.599620019005, 2238.563480121377, 2274.9886250568807, 2276.3398614401804, 2277.8519396239276, 2279.384566167134, 2189.1462329691435, 2267.8979445924974, 2282.6461898405773, 2298.7241531016216, 2304.6888670029584, 1988.7911769264235, 2062.7730949595534, 2063.7007633893077, 2201.5156665533636, 2270.0806173499677, 2258.785415385544, 2277.89749461273, 2280.1107568048474, 2281.908723651052, 2284.942876428086, 2226.677131815894, 2251.3696165073807, 2253.887305634728, 2262.988685056581, 2265.694131292271, 8043.0, 8056.637451314705, 8135.227153420416, 8145.429819912609, 8154.388420868399, 4902.950970490285, 5057.013882292954, 5062.620303477254, 5074.822381216677, 5084.593232541388, 2936.5154749466315, 3266.8039917604783, 3405.6934875861043, 3416.7437442191685, 3467.219875528011, 1017.2319898476603, 1123.230587047875, 1188.1089183112665, 1206.4329765010432, 1248.8813562711528, 10347.482625868752, 10457.0, 10509.789804203949, 10527.999840015264, 10528.631497897613, 4306.246406878784, 4463.598276155161, 4536.364908912688, 4547.818087276538, 4549.158405694957, 9159.175674189355, 9166.724998250063, 9173.266138708887, 9196.218321442619, 9224.123708247706, 5001.624878134155, 5066.265391518209, 5076.746162691925, 5080.161773307437, 5086.033653605803, 4546.954530454719, 4590.334401511762, 4615.469221039556, 4645.744484053342, 4647.326137710081, 3691.5570131583845, 3752.493413389212, 3767.1335592813807, 3794.5067141271456, 3810.733248672622, 1211.4669545399995, 1214.6173955204083, 1219.7072702551325, 1230.194222784078, 1235.178606226849, 733.2154594583851, 773.9419543534258, 776.2547953964257, 781.9296263336327, 805.1103530598721, 5225.387864612705, 5258.553022993012, 5375.865603359869, 5594.748236329375, 5613.410591887875, 558.6620094842643, 585.631052436962, 585.6369051188291, 586.8004878341299, 613.6164896939413, 3556.822158892045, 3694.8706795262037, 3799.1262009737916, 3811.3520701480816, 3817.446470261852, 9248.861267081, 9747.074027967332, 9751.3661611995, 9773.609055637837, 9779.755506112404, 9472.479013654316, 9513.714588562354, 9572.56923438447, 9714.854277185706, 9756.12194902448, 7564.697412103455, 7596.962015189889, 7653.655585498666, 7680.654370553339, 7684.0, 5340.679559226459, 5396.109641909198, 5421.512063914267, 5443.374011988513, 5475.835724928259, 8882.022977472601, 8981.236594889508, 9027.684031058881, 9038.32212584072, 9039.909600903908, 7592.278733520307, 8878.378513503993, 9143.817123657356, 9175.908240917772, 9240.168384845127, 5901.232839730807, 6885.414739747178, 6981.371676549135, 6983.127109111361, 6983.406410454965, 7674.194209608134, 7714.6914123434435, 7718.266764657348, 7726.304632583095, 7747.147813740597, 6795.422389096784, 6807.115075040213, 6861.4853885957755, 6906.136732908387, 7020.22777494465, 6720.092787473628, 6723.0, 6785.796426107225, 6868.965655171892, 6887.20797108339, 4926.531979462089, 6252.124702541557, 6320.4311611955145, 6325.77859774924, 6339.175907132043, 4845.709257444423, 4915.26271059344, 4924.4090709115135, 4935.259711043373, 5085.77114029877, 3750.3249415105543, 4926.5073492650445, 4988.825391111293, 5023.422306434807, 5079.085764562415], "value": 4223.237376835568, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.66496828013385, 360.27404779369573, 363.5092624956308, 372.22205590316236, 376.73063759412014, 4170.416141740159, 5215.58275337973, 5269.868253293665, 5319.973400133003, 5411.729413529318, 4315.590018948203, 5095.668781529198, 5172.224166375046, 5233.64485981308, 5237.7381130943495, 4183.267928112568, 4215.683823713219, 4223.429836972008, 4227.640650544708, 4229.217594744982, 3710.9257814843822, 3985.2627263956115, 4080.9591904080876, 4227.598378154071, 4230.598093181143, 4137.462129923106, 4156.334986402177, 4173.56177601352, 4173.916521669565, 4179.519355274152, 3645.562532496091, 4123.649489793371, 4161.91676166475, 4165.83336666533, 4184.832606695729, 2914.446255211524, 3228.370467758788, 3523.7448033258734, 3677.4667673187237, 3679.920844722723, 4529.433820772403, 4936.358273424467, 5221.425643179247, 5236.895262094775, 5249.816256431045, 4494.842680506166, 4759.643026772971, 4763.880902977418, 4784.258439941815, 4803.3995750531185, 5629.9437005629825, 5645.32256129263, 5660.971695141498, 5663.603547751659, 5676.290463692038, 3421.7091547218515, 3508.4211105167874, 3824.579296277407, 3857.4792403025795, 4033.556308806029, 5377.46225377463, 5564.777408903639, 5874.356539557216, 5887.381824908366, 5899.498542623839, 3313.5858017747782, 3419.3845107880325, 3870.148567315186, 3873.8063096845044, 3885.4846609822193, 3666.6883314917995, 3908.7654740715648, 3949.3483575210344, 3951.3009405955713, 3955.4462375267485, 4529.796159172845, 4584.931226031612, 4585.243434833215, 4588.174128656874, 4601.470830854495, 4738.573528382462, 5618.353889302783, 5631.577631677641, 5676.403977582377, 5736.913946290809, 1108.7893300273083, 1170.7473003885761, 1174.9118816088828, 1230.5877531027206, 1252.599168266146, 818.926296633294, 847.8516259654535, 853.5134973065293, 869.3566760597113, 879.0286733159894, 4215.473065866767, 4230.450041494586, 4287.506936702319, 4757.833475828329, 5036.294918711384, 5461.535769459561, 5503.807366742145, 5571.690652696613, 5606.187102870061, 5638.182463542846, 4255.468066491689, 4962.131626965267, 4966.932109596381, 5344.942197254008, 5369.060414427459, 2895.2038189498057, 2904.361040571071, 2909.3744844858456, 2914.519104347759, 2922.444735500248, 4628.190066738307, 4646.535346465326, 4657.417822772153, 5256.500632439912, 5717.285339332583, 4858.951410485921, 4936.160852654985, 5337.439568845215, 5583.213371721092, 5658.613639658228, 5658.575606829504, 5706.0, 5720.313562372458, 5721.5708821838525, 5753.784792854697, 4205.873823785291, 4738.715677059387, 4775.355327030807, 5374.220737992891, 5474.822913073708, 4896.583790377716, 4968.0, 5075.847724568269, 5224.817131400383, 5312.0, 4665.4168228971375, 5224.346956630421, 5389.218563308376, 5573.7180448496265, 5805.41945805433, 3429.622741498383, 3637.7623914715396, 3641.7632853865475, 3646.9817650911577, 3655.3603119453455, 5028.974855125701, 5481.671099734029, 5539.473749993744, 5572.192032155396, 5592.3848376677715, 4488.730676159441, 4594.931076033795, 5212.348456442945, 5277.050130976461, 5315.01672190646, 4385.144896745104, 4460.977695111504, 4705.541282202528, 4885.497701995578, 4965.702057876539, 4715.6463265255925, 5168.819091331786, 5308.57531397495, 5312.282841816305, 5366.785328586813, 4938.604911606991, 4996.325496057985, 5055.912978709595, 5282.022825777243, 5620.747066382104, 5327.813526526553, 5470.835874923759, 5514.200440936122, 5519.862003449946, 5541.861453463695], "value": 3997.9086127054384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.9472039597032, 704.66176235407, 706.1420374245295, 799.892014578032, 829.0797215091247, 14576.0, 15478.922605386986, 15489.92255038726, 15511.146886921215, 15536.456224032161, 10649.893501064988, 14010.719785604282, 14166.079204851678, 14207.0, 14231.857681423182, 871.5250188647186, 895.995520022399, 897.6364572348209, 900.8063266397724, 905.6965916417975, 898.7932775461647, 901.4456109492647, 901.8106197698459, 903.8960519540238, 904.9999750275949, 937.3298091864316, 937.9718608441758, 939.8825146856643, 942.3639043645546, 942.8821397325335, 802.7712102050888, 905.1762895764822, 929.9163075323254, 932.4917919733762, 934.0799312677008, 676.1885737115481, 693.6323748413349, 698.0262533765369, 700.2577268095835, 700.5586480517293, 15561.299741511659, 15561.688766224612, 15565.143917084555, 15586.922065389714, 15587.376504939788, 12749.42627581761, 13526.256055916921, 13991.23048232357, 14034.649133771738, 14041.578752637437, 11776.0, 11826.645200643909, 11841.052715782716, 11954.521819127225, 12006.819897701542, 3604.098975256186, 3611.205534782344, 3649.9635003649887, 3658.743887927846, 3718.7954662493553, 99530.50234748852, 105684.0, 109063.0, 109133.45433272782, 109307.0, 10147.594096236215, 10195.949020254851, 10212.387256764618, 10318.948405257926, 10593.417362045007, 21134.260300889397, 21822.236221732164, 21962.47431545393, 22084.558308833733, 22160.113595456, 7550.924490755132, 7560.9621951889885, 7573.2805383488485, 7620.428467864932, 7786.727464538714, 4199.076203235344, 4285.671769129297, 4306.267934451088, 4322.3516472528745, 4407.096545208225, 3404.506346579732, 3435.914102147466, 3462.255615042778, 3508.52634894286, 3514.68367846895, 624.5596854217765, 626.6083697688945, 646.7542333913083, 678.5996262205323, 691.4261163234503, 666.9499787515956, 683.040328338685, 707.3633729643362, 721.6932803558467, 745.3552676934413, 9472.668456603986, 9577.0, 9693.951530242304, 10151.847722284172, 10434.42610656406, 637.6715991264502, 679.6805501414327, 686.306830101601, 687.9036934829128, 692.1555702043499, 4615.9076818463445, 4939.185034469344, 4952.62855285855, 4953.628477864175, 4967.3542439482635, 89164.0, 94207.11585768247, 103152.96847031584, 103804.48097759463, 103956.0, 61626.0, 88585.0, 90094.54952725195, 90793.0, 91949.0805091954, 62850.685746570976, 64504.67747661232, 65435.018474722914, 76592.61703691445, 79964.0, 10340.224483163642, 10601.946990265, 10752.40861752611, 10814.675559733221, 10852.67441976742, 42440.78779606206, 49699.0, 50269.0, 52777.47222527879, 54047.72976135094, 66820.66589667021, 68823.3117668837, 69967.65016174887, 70301.64849175721, 71159.64420177866, 12367.567135150226, 12368.567100151451, 12550.372481376076, 12679.619611411672, 12731.55439559611, 14009.509667161601, 14200.78698819498, 14231.319839344587, 14276.71446571042, 14372.784408234094, 13080.476780928657, 13198.538051168163, 13284.468621255042, 13427.127236729972, 13520.0, 10750.408727519749, 13187.274699891597, 13462.192268463923, 13645.385957631732, 13825.446982120602, 12927.482900683868, 13070.150440221736, 13346.532871349456, 13613.610616200913, 13779.0, 10538.947305263424, 11049.944750276198, 11268.267562608404, 11332.0, 11380.203385763249, 8633.39566230358, 10334.638287659896, 10557.841632375366, 10675.51960161779, 10744.892551074388], "value": 7473.307811499417, "unit": "score"}], "name": "dromaeo_css", "value": 7145.9875481387635, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 111.0, 112.0, 113.0, 112.0, 113.0, 113.0, 111.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 107.0, 108.0, 142.0, 109.0, 104.0, 106.0, 108.0, 126.0, 110.0], "value": 115.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 180.0, 183.0, 181.0, 183.0, 182.0, 186.0, 182.0, 178.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 58.0, 86.0, 61.0, 58.0, 58.0, 58.0, 76.0, 74.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 86.0, 68.0, 68.0, 69.0, 82.0, 68.0, 67.0, 84.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 131.0, 86.0, 84.0, 86.0, 83.0, 88.0, 86.0, 88.0, 84.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 117.0, 170.0, 95.0, 95.0, 97.0, 96.0], "value": 105.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 133.0, 85.0, 85.0, 84.0, 87.0, 86.0, 89.0, 85.0, 85.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 55.0, 56.0, 57.0, 55.0, 56.0, 56.0, 58.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 80.0, 77.0, 74.0, 71.0, 71.0, 71.0, 71.0, 72.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 92.0, 86.0, 86.0, 86.0, 76.0, 85.0, 107.0, 106.0, 109.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 145.0, 138.0, 141.0, 139.0, 139.0, 138.0, 183.0, 181.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 176.0, 177.0, 176.0, 176.0, 177.0, 178.0, 175.0, 175.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 80.0, 79.0, 89.0, 86.0, 76.0, 69.0, 67.0, 66.0, 67.0], "value": 74.8, "unit": "score"}], "name": "kraken", "value": 1461.9, "alertThreshold": 2.0}]} 09:44:59 INFO - Return code: 0 09:44:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:44:59 INFO - # TBPL SUCCESS # 09:44:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:59 INFO - 2017-01-06 09:44:59.370 screenresolution[1656:9708] starting screenresolution argv=screenresolution get 09:44:59 INFO - 2017-01-06 09:44:59.373 screenresolution[1656:9708] Display 0: 1600x1200x32@60 09:44:59 INFO - 2017-01-06 09:44:59.378 screenresolution[1657:9711] starting screenresolution argv=screenresolution list 09:44:59 INFO - Available Modes on Display 0 09:44:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:59 INFO - Intel Iris: 09:44:59 INFO - Chipset Model: Intel Iris 09:44:59 INFO - Type: GPU 09:44:59 INFO - Bus: Built-In 09:44:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:59 INFO - Vendor: Intel (0x8086) 09:44:59 INFO - Device ID: 0x0a2e 09:44:59 INFO - Revision ID: 0x0009 09:44:59 INFO - Displays: 09:44:59 INFO - Display: 09:44:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:59 INFO - Display Serial Number: 1600x1200 60 09:44:59 INFO - Main Display: Yes 09:44:59 INFO - Mirror: Off 09:44:59 INFO - Online: Yes 09:44:59 INFO - Rotation: Supported 09:44:59 INFO - Return code: 0 09:44:59 INFO - Running post-action listener: _resource_record_post_action 09:44:59 INFO - [mozharness: 2017-01-06 17:44:59.620378Z] Finished run-tests step (success) 09:44:59 INFO - Running post-run listener: _resource_record_post_run 09:44:59 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 37659648; Write bytes: 550851584; Read time: 240; Write time: 1649 09:44:59 INFO - TinderboxPrint: CPU usage
53.8% 09:44:59 INFO - TinderboxPrint: I/O read bytes / time
37,659,648 / 240 09:44:59 INFO - TinderboxPrint: I/O write bytes / time
550,851,584 / 1,649 09:44:59 INFO - TinderboxPrint: CPU idle
2,219.9 (46.2%) 09:44:59 INFO - TinderboxPrint: CPU system
955.1 (19.9%) 09:44:59 INFO - TinderboxPrint: CPU user
1,626.7 (33.9%) 09:44:59 INFO - TinderboxPrint: Swap in / out
287,117,312 / 0 09:44:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226947584; Write bytes: 222631424; Read time: 16358; Write time: 481 09:44:59 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34317312; Write bytes: 296578560; Read time: 216; Write time: 1094 09:45:00 INFO - Running post-run listener: _upload_blobber_files 09:45:00 INFO - Blob upload gear active. 09:45:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:45:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:45:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:45:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:45:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:45:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:01 INFO - (blobuploader) - INFO - Done attempting. 09:45:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:45:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:45:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:03 INFO - (blobuploader) - INFO - Done attempting. 09:45:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:45:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:45:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:05 INFO - (blobuploader) - INFO - Done attempting. 09:45:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:45:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:45:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:45:08 INFO - (blobuploader) - INFO - Done attempting. 09:45:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:45:08 INFO - Return code: 0 09:45:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:45:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:45:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9996606ae5f67ad2ac17cb70e2c202ab1abf288da54e8f4e5ffe529c69c68bea2e18be48fb1a0c822266acee2c6b870b54a280cd68084818668f80ed6c174f11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4c8a9f65802c217858304cc7429e878aa9539e69285a62025093fbfade527a3015b497de7d4aa34c9e5f6a70b2d03b5322fd7691f50478f10f52058de511c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a04f0e874994f070d33dbcfb3c1451634cc22005e9bd14e32d760fb7258f6548b72ebbb90fad086ff5372396d71b4cee4ee5e6bb388e1c048837cd4a10bcd55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59046474011c67484527ae9e03c93383dba7e6ebeb2451dfe612eb818890794727e30cbd3ff40d6970f4b844e01a55a6aa3bc799d516294aceb5e0b2eabf970"} 09:45:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:45:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:45:08 INFO - Contents: 09:45:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9996606ae5f67ad2ac17cb70e2c202ab1abf288da54e8f4e5ffe529c69c68bea2e18be48fb1a0c822266acee2c6b870b54a280cd68084818668f80ed6c174f11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4c8a9f65802c217858304cc7429e878aa9539e69285a62025093fbfade527a3015b497de7d4aa34c9e5f6a70b2d03b5322fd7691f50478f10f52058de511c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a04f0e874994f070d33dbcfb3c1451634cc22005e9bd14e32d760fb7258f6548b72ebbb90fad086ff5372396d71b4cee4ee5e6bb388e1c048837cd4a10bcd55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59046474011c67484527ae9e03c93383dba7e6ebeb2451dfe612eb818890794727e30cbd3ff40d6970f4b844e01a55a6aa3bc799d516294aceb5e0b2eabf970"} 09:45:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:45:08 INFO - Copying logs to upload dir... 09:45:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:45:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.532132 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-06 09:45:08.215186) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 09:45:08.223190) ========= 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.7HBMS4hBvD/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.6N2BhrAP93/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/Firefox/sha512/9996606ae5f67ad2ac17cb70e2c202ab1abf288da54e8f4e5ffe529c69c68bea2e18be48fb1a0c822266acee2c6b870b54a280cd68084818668f80ed6c174f11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4c8a9f65802c217858304cc7429e878aa9539e69285a62025093fbfade527a3015b497de7d4aa34c9e5f6a70b2d03b5322fd7691f50478f10f52058de511c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a04f0e874994f070d33dbcfb3c1451634cc22005e9bd14e32d760fb7258f6548b72ebbb90fad086ff5372396d71b4cee4ee5e6bb388e1c048837cd4a10bcd55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59046474011c67484527ae9e03c93383dba7e6ebeb2451dfe612eb818890794727e30cbd3ff40d6970f4b844e01a55a6aa3bc799d516294aceb5e0b2eabf970"} build_url:https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011050 build_url: 'https://queue.taskcluster.net/v1/task/V80rXjlZSImr6CyRXOsU2w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9996606ae5f67ad2ac17cb70e2c202ab1abf288da54e8f4e5ffe529c69c68bea2e18be48fb1a0c822266acee2c6b870b54a280cd68084818668f80ed6c174f11", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c4c8a9f65802c217858304cc7429e878aa9539e69285a62025093fbfade527a3015b497de7d4aa34c9e5f6a70b2d03b5322fd7691f50478f10f52058de511c7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a04f0e874994f070d33dbcfb3c1451634cc22005e9bd14e32d760fb7258f6548b72ebbb90fad086ff5372396d71b4cee4ee5e6bb388e1c048837cd4a10bcd55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d59046474011c67484527ae9e03c93383dba7e6ebeb2451dfe612eb818890794727e30cbd3ff40d6970f4b844e01a55a6aa3bc799d516294aceb5e0b2eabf970"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-06 09:45:08.267398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:45:08.270990) ========= 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.7HBMS4hBvD/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.6N2BhrAP93/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.004940 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 09:45:08.320411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 09:45:08.323831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 09:45:08.324239) ========= ========= Total master_lag: 1.84 =========