builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0312 starttime: 1486129466.08 results: success (0) buildid: 20170203042710 builduid: 75706f7be14246b8b35da4768131640e revision: 9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.084894) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.085369) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.085652) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.163762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.164023) ========= 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.j5eZlMUQJQ/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.vreamwBMJa/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-02-03 05:44:26-- 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% 62.8M=0s 2017-02-03 05:44:26 (62.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.665045 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.856392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.856701) ========= 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.j5eZlMUQJQ/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.vreamwBMJa/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.024780 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:26.905178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 05:44:26.905541) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23 --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 9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j5eZlMUQJQ/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.vreamwBMJa/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-02-03 05:44:27,013 truncating revision to first 12 chars 2017-02-03 05:44:27,013 Setting DEBUG logging. 2017-02-03 05:44:27,013 attempt 1/10 2017-02-03 05:44:27,013 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9f17a6fc04f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-03 05:44:27,780 unpacking tar archive at: mozilla-central-9f17a6fc04f9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.054801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-03 05:44:27.983103) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:27.983422) ========= script_repo_revision: 9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:27.983809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:27.984138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-03 05:44:28.003526) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-03 05:44:28.003887) ========= /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.j5eZlMUQJQ/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.vreamwBMJa/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:44:28 INFO - MultiFileLogger online at 20170203 05:44:28 in /builds/slave/test 05:44:28 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 05:44:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:44:28 INFO - {'append_to_log': False, 05:44:28 INFO - 'base_work_dir': '/builds/slave/test', 05:44:28 INFO - 'blob_upload_branch': 'Firefox', 05:44:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:44:28 INFO - 'branch': 'Firefox', 05:44:28 INFO - 'buildbot_json_path': 'buildprops.json', 05:44:28 INFO - 'config_files': ('talos/mac_config.py',), 05:44:28 INFO - 'default_actions': ('clobber', 05:44:28 INFO - 'read-buildbot-config', 05:44:28 INFO - 'download-and-extract', 05:44:28 INFO - 'populate-webroot', 05:44:28 INFO - 'create-virtualenv', 05:44:28 INFO - 'install', 05:44:28 INFO - 'run-tests'), 05:44:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:44:28 INFO - 'download_minidump_stackwalk': True, 05:44:28 INFO - 'download_symbols': 'ondemand', 05:44:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:44:28 INFO - 'tooltool.py': '/tools/tooltool.py', 05:44:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:44:28 INFO - '/tools/misc-python/virtualenv.py')}, 05:44:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:44:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:44:28 INFO - 'installer_path': 'installer.exe', 05:44:28 INFO - 'log_level': 'info', 05:44:28 INFO - 'log_name': 'talos', 05:44:28 INFO - 'log_to_console': True, 05:44:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:44:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:44:28 INFO - 'opt_config_files': (), 05:44:28 INFO - 'pip_index': False, 05:44:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:44:28 INFO - 'cmd': ('bash', 05:44:28 INFO - '-c', 05:44:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:44:28 INFO - 'enabled': True, 05:44:28 INFO - 'halt_on_failure': False, 05:44:28 INFO - 'name': 'check_screen_resolution'},), 05:44:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:44:28 INFO - 'cmd': ('bash', 05:44:28 INFO - '-c', 05:44:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:44:28 INFO - 'enabled': True, 05:44:28 INFO - 'halt_on_failure': False, 05:44:28 INFO - 'name': 'check_screen_resolution'},), 05:44:28 INFO - 'run_cmd_checks_enabled': True, 05:44:28 INFO - 'sps_profile': False, 05:44:28 INFO - 'sps_profile_interval': 0, 05:44:28 INFO - 'suite': 'dromaeojs', 05:44:28 INFO - 'system_bits': '32', 05:44:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:44:28 INFO - 'title': 't-yosemite-r7-0312', 05:44:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:44:28 INFO - 'use_talos_json': True, 05:44:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:44:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:44:28 INFO - 'work_dir': 'build'} 05:44:28 INFO - [mozharness: 2017-02-03 13:44:28.223793Z] Running clobber step. 05:44:28 INFO - Running pre-action listener: _resource_record_pre_action 05:44:28 INFO - Running main action method: clobber 05:44:28 INFO - rmtree: /builds/slave/test/build 05:44:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:44:30 INFO - Running post-action listener: _resource_record_post_action 05:44:30 INFO - [mozharness: 2017-02-03 13:44:30.766730Z] Finished clobber step (success) 05:44:30 INFO - [mozharness: 2017-02-03 13:44:30.766883Z] Running read-buildbot-config step. 05:44:30 INFO - Running pre-action listener: _resource_record_pre_action 05:44:30 INFO - Running main action method: read_buildbot_config 05:44:30 INFO - Using buildbot properties: 05:44:30 INFO - { 05:44:30 INFO - "project": "", 05:44:30 INFO - "product": "firefox", 05:44:30 INFO - "script_repo_revision": "production", 05:44:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:44:30 INFO - "repository": "", 05:44:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:44:30 INFO - "buildid": "20170203042710", 05:44:30 INFO - "pgo_build": "False", 05:44:30 INFO - "basedir": "/builds/slave/test", 05:44:30 INFO - "buildnumber": 3, 05:44:30 INFO - "slavename": "t-yosemite-r7-0312", 05:44:30 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:44:30 INFO - "platform": "yosemite_r7", 05:44:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:44:30 INFO - "branch": "mozilla-central", 05:44:30 INFO - "repo_path": "mozilla-central", 05:44:30 INFO - "revision": "9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23", 05:44:30 INFO - "stage_platform": "macosx64", 05:44:30 INFO - "builduid": "75706f7be14246b8b35da4768131640e", 05:44:30 INFO - "slavebuilddir": "test" 05:44:30 INFO - } 05:44:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 05:44:30 INFO - Running post-action listener: _resource_record_post_action 05:44:30 INFO - [mozharness: 2017-02-03 13:44:30.770265Z] Finished read-buildbot-config step (success) 05:44:30 INFO - [mozharness: 2017-02-03 13:44:30.770415Z] Running download-and-extract step. 05:44:30 INFO - Running pre-action listener: _resource_record_pre_action 05:44:30 INFO - Running main action method: download_and_extract 05:44:30 INFO - mkdir: /builds/slave/test/build/tests 05:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:30 INFO - trying https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 05:44:30 INFO - Downloading https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 05:44:32 INFO - Downloaded 1199 bytes. 05:44:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:44:32 INFO - Using the following test package requirements: 05:44:32 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:44:32 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 05:44:32 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 05:44:32 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'jsshell-mac.zip'], 05:44:32 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 05:44:32 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:44:32 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 05:44:32 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 05:44:32 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 05:44:32 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:44:32 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 05:44:32 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:44:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:44:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:44:32 INFO - Fetch https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:44:33 INFO - Expected file size: 34849865 05:44:33 INFO - Obtained file size: 34849865 05:44:40 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:44:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:44:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:44:40 INFO - Fetch https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:44:41 INFO - Expected file size: 34849865 05:44:41 INFO - Obtained file size: 34849865 05:44:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 05:44:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:44:47 INFO - Fetch https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 05:44:48 INFO - Expected file size: 14403454 05:44:48 INFO - Obtained file size: 14403454 05:44:49 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')]} 05:44:49 INFO - trying https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:44:49 INFO - Downloading https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 05:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 05:44:51 INFO - Downloaded 70289242 bytes. 05:44:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:44:51 INFO - mkdir: /builds/slave/test/properties 05:44:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:44:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:44:51 INFO - Contents: 05:44:51 INFO - build_url:https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:44:51 INFO - Running post-action listener: _resource_record_post_action 05:44:51 INFO - Running post-action listener: set_extra_try_arguments 05:44:51 INFO - [mozharness: 2017-02-03 13:44:51.804548Z] Finished download-and-extract step (success) 05:44:51 INFO - [mozharness: 2017-02-03 13:44:51.804701Z] Running populate-webroot step. 05:44:51 INFO - Running pre-action listener: _resource_record_pre_action 05:44:51 INFO - Running main action method: populate_webroot 05:44:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:44:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:44:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:44:51 INFO - '--tppagecycles', 05:44:51 INFO - '7'], 05:44:51 INFO - 'tests': ['tsvgm']}}, 05:44:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:44:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:44:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:44:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:44:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['damp', 'tps']}, 05:44:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'tests': ['damp', 'tps']}, 05:44:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['dromaeo_dom']}, 05:44:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:44:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['basic_compositor_video']}, 05:44:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:44:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['a11yr', 05:44:51 INFO - 'ts_paint', 05:44:51 INFO - 'tpaint', 05:44:51 INFO - 'sessionrestore', 05:44:51 INFO - 'sessionrestore_no_auto_restore', 05:44:51 INFO - 'tabpaint']}, 05:44:51 INFO - 'other-e10s': {'tests': ['a11yr', 05:44:51 INFO - 'ts_paint', 05:44:51 INFO - 'tpaint', 05:44:51 INFO - 'sessionrestore', 05:44:51 INFO - 'sessionrestore_no_auto_restore', 05:44:51 INFO - 'tabpaint']}, 05:44:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:44:51 INFO - 'ts_paint', 05:44:51 INFO - 'tpaint', 05:44:51 INFO - 'sessionrestore', 05:44:51 INFO - 'sessionrestore_no_auto_restore', 05:44:51 INFO - 'tabpaint']}, 05:44:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:44:51 INFO - 'ts_paint', 05:44:51 INFO - 'tpaint', 05:44:51 INFO - 'sessionrestore', 05:44:51 INFO - 'sessionrestore_no_auto_restore', 05:44:51 INFO - 'tabpaint']}, 05:44:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['a11yr', 05:44:51 INFO - 'ts_paint', 05:44:51 INFO - 'tpaint', 05:44:51 INFO - 'sessionrestore', 05:44:51 INFO - 'sessionrestore_no_auto_restore', 05:44:51 INFO - 'tabpaint']}, 05:44:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['a11yr', 05:44:51 INFO - 'ts_paint', 05:44:51 INFO - 'tpaint', 05:44:51 INFO - 'sessionrestore', 05:44:51 INFO - 'sessionrestore_no_auto_restore', 05:44:51 INFO - 'tabpaint']}, 05:44:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['tsvgx', 05:44:51 INFO - 'tsvgr_opacity', 05:44:51 INFO - 'tart', 05:44:51 INFO - 'tscrollx', 05:44:51 INFO - 'cart', 05:44:51 INFO - 'tsvg_static']}, 05:44:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:44:51 INFO - 'tsvgr_opacity', 05:44:51 INFO - 'tart', 05:44:51 INFO - 'tscrollx', 05:44:51 INFO - 'cart', 05:44:51 INFO - 'tsvg_static']}, 05:44:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'talos_options': ['--disable-e10s'], 05:44:51 INFO - 'tests': ['tp5o']}, 05:44:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:44:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'talos_options': ['--disable-e10s', 05:44:51 INFO - '--xperf_path', 05:44:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:51 INFO - 'tests': ['tp5n']}, 05:44:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:44:51 INFO - 'talos_options': ['--xperf_path', 05:44:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:51 INFO - 'tests': ['tp5n']}}, 05:44:51 INFO - 'talos.zip': {'path': '', 05:44:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:44:51 INFO - Running post-action listener: _resource_record_post_action 05:44:51 INFO - [mozharness: 2017-02-03 13:44:51.822836Z] Finished populate-webroot step (success) 05:44:51 INFO - [mozharness: 2017-02-03 13:44:51.822984Z] Running create-virtualenv step. 05:44:51 INFO - Running pre-action listener: _resource_record_pre_action 05:44:51 INFO - Running main action method: create_virtualenv 05:44:51 INFO - Creating virtualenv /builds/slave/test/build/venv 05:44:51 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 05:44:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:44:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:44:51 INFO - Using real prefix '/tools/python27' 05:44:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:44:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:44:55 INFO - Installing pip.................done. 05:44:55 INFO - Return code: 0 05:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:44:55 INFO - Reading from file tmpfile_stdout 05:44:55 INFO - Output received: 05:44:55 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) 05:44:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:55 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:44:55 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 05:44:55 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 05:44:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:44:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:55 INFO - 'HOME': '/Users/cltbld', 05:44:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:55 INFO - 'LOGNAME': 'cltbld', 05:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:55 INFO - 'MOZ_NO_REMOTE': '1', 05:44:55 INFO - 'NO_EM_RESTART': '1', 05:44:55 INFO - 'PAGER': '/bin/cat', 05:44:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:55 INFO - 'PWD': '/builds/slave/test', 05:44:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:55 INFO - 'SHELL': '/bin/bash', 05:44:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:44:55 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:44:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:55 INFO - 'USER': 'cltbld', 05:44:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:55 INFO - 'XPC_FLAGS': '0x0', 05:44:55 INFO - 'XPC_SERVICE_NAME': '0', 05:44:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:56 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 05:44:56 INFO - Cleaning up... 05:44:56 INFO - Return code: 0 05:44:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:44:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:44:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:44:56 INFO - 'HOME': '/Users/cltbld', 05:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:44:56 INFO - 'LOGNAME': 'cltbld', 05:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:56 INFO - 'MOZ_NO_REMOTE': '1', 05:44:56 INFO - 'NO_EM_RESTART': '1', 05:44:56 INFO - 'PAGER': '/bin/cat', 05:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:56 INFO - 'PWD': '/builds/slave/test', 05:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:44:56 INFO - 'SHELL': '/bin/bash', 05:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:44:56 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:44:56 INFO - 'USER': 'cltbld', 05:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:56 INFO - 'XPC_FLAGS': '0x0', 05:44:56 INFO - 'XPC_SERVICE_NAME': '0', 05:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:56 INFO - Downloading/unpacking psutil>=3.1.1 05:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:44:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:44:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:00 INFO - Installing collected packages: psutil 05:45:00 INFO - Running setup.py install for psutil 05:45:00 INFO - building 'psutil._psutil_osx' extension 05:45:00 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 05:45:01 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 05:45:01 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 05:45:01 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 05:45:01 INFO - building 'psutil._psutil_posix' extension 05:45:01 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 05:45:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:45:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:45:01 INFO - ^ 05:45:01 INFO - 1 warning generated. 05:45:01 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 05:45:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:01 INFO - Successfully installed psutil 05:45:01 INFO - Cleaning up... 05:45:01 INFO - Return code: 0 05:45:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:01 INFO - 'HOME': '/Users/cltbld', 05:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:01 INFO - 'LOGNAME': 'cltbld', 05:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:01 INFO - 'MOZ_NO_REMOTE': '1', 05:45:01 INFO - 'NO_EM_RESTART': '1', 05:45:01 INFO - 'PAGER': '/bin/cat', 05:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:01 INFO - 'PWD': '/builds/slave/test', 05:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:01 INFO - 'SHELL': '/bin/bash', 05:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:01 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:01 INFO - 'USER': 'cltbld', 05:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:01 INFO - 'XPC_FLAGS': '0x0', 05:45:01 INFO - 'XPC_SERVICE_NAME': '0', 05:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:45:04 INFO - Installing collected packages: mozsystemmonitor 05:45:04 INFO - Running setup.py install for mozsystemmonitor 05:45:04 INFO - Successfully installed mozsystemmonitor 05:45:04 INFO - Cleaning up... 05:45:04 INFO - Return code: 0 05:45:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:45: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')]} 05:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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')]} 05:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:04 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:04 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 05:45:04 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 05:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:04 INFO - 'HOME': '/Users/cltbld', 05:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:04 INFO - 'LOGNAME': 'cltbld', 05:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:04 INFO - 'MOZ_NO_REMOTE': '1', 05:45:04 INFO - 'NO_EM_RESTART': '1', 05:45:04 INFO - 'PAGER': '/bin/cat', 05:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:04 INFO - 'PWD': '/builds/slave/test', 05:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:04 INFO - 'SHELL': '/bin/bash', 05:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:04 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:04 INFO - 'USER': 'cltbld', 05:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:04 INFO - 'XPC_FLAGS': '0x0', 05:45:04 INFO - 'XPC_SERVICE_NAME': '0', 05:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:05 INFO - Downloading/unpacking jsonschema==2.5.1 05:45:05 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 05:45:05 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 05:45:05 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 05:45:05 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 05:45:05 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 05:45:05 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 05:45:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:08 INFO - warning: no files found matching '*.txt' 05:45:08 INFO - no previously-included directories found matching 'build' 05:45:08 INFO - no previously-included directories found matching 'dist' 05:45:08 INFO - no previously-included directories found matching '.git*' 05:45:08 INFO - Installing collected packages: jsonschema, functools32 05:45:08 INFO - Running setup.py install for functools32 05:45:08 INFO - warning: no files found matching '*.txt' 05:45:08 INFO - no previously-included directories found matching 'build' 05:45:08 INFO - no previously-included directories found matching 'dist' 05:45:08 INFO - no previously-included directories found matching '.git*' 05:45:08 INFO - Successfully installed jsonschema functools32 05:45:08 INFO - Cleaning up... 05:45:08 INFO - Return code: 0 05:45:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:08 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:08 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 05:45:08 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 05:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:08 INFO - 'HOME': '/Users/cltbld', 05:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:08 INFO - 'LOGNAME': 'cltbld', 05:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:08 INFO - 'MOZ_NO_REMOTE': '1', 05:45:08 INFO - 'NO_EM_RESTART': '1', 05:45:08 INFO - 'PAGER': '/bin/cat', 05:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:08 INFO - 'PWD': '/builds/slave/test', 05:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:08 INFO - 'SHELL': '/bin/bash', 05:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:08 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:08 INFO - 'USER': 'cltbld', 05:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:08 INFO - 'XPC_FLAGS': '0x0', 05:45:08 INFO - 'XPC_SERVICE_NAME': '0', 05:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:45:08 INFO - Cleaning up... 05:45:08 INFO - Return code: 0 05:45:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:08 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:08 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 05:45:08 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 05:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:08 INFO - 'HOME': '/Users/cltbld', 05:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:08 INFO - 'LOGNAME': 'cltbld', 05:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:08 INFO - 'MOZ_NO_REMOTE': '1', 05:45:08 INFO - 'NO_EM_RESTART': '1', 05:45:08 INFO - 'PAGER': '/bin/cat', 05:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:08 INFO - 'PWD': '/builds/slave/test', 05:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:08 INFO - 'SHELL': '/bin/bash', 05:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:08 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:08 INFO - 'USER': 'cltbld', 05:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:08 INFO - 'XPC_FLAGS': '0x0', 05:45:08 INFO - 'XPC_SERVICE_NAME': '0', 05:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:45:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45:12 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 05:45:12 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 05:45:12 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 05:45:12 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 05:45:12 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 05:45:12 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 05:45:12 INFO - Downloading docopt-0.6.1.tar.gz 05:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:45:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:45:12 INFO - Running setup.py install for blobuploader 05:45:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:45:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:45:13 INFO - Running setup.py install for requests 05:45:13 INFO - Running setup.py install for docopt 05:45:13 INFO - Successfully installed blobuploader requests docopt 05:45:13 INFO - Cleaning up... 05:45:13 INFO - Return code: 0 05:45:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:13 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')]} 05:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:13 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')]} 05:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:13 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:13 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 05:45:13 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 05:45:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:13 INFO - 'HOME': '/Users/cltbld', 05:45:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:13 INFO - 'LOGNAME': 'cltbld', 05:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:13 INFO - 'MOZ_NO_REMOTE': '1', 05:45:13 INFO - 'NO_EM_RESTART': '1', 05:45:13 INFO - 'PAGER': '/bin/cat', 05:45:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:13 INFO - 'PWD': '/builds/slave/test', 05:45:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:13 INFO - 'SHELL': '/bin/bash', 05:45:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:13 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:13 INFO - 'USER': 'cltbld', 05:45:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:13 INFO - 'XPC_FLAGS': '0x0', 05:45:13 INFO - 'XPC_SERVICE_NAME': '0', 05:45:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ot2JXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-T19ncy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-DjlrLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-nEBWt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-uhMbfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9Ti3Jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ZFPa2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-EK1odh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Q8t4w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:14 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-lxQnoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-rCLMno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-3pkXVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-4RAOI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9NfxU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-5iCMkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-SvFhun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:15 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-SQYlcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:45:15 INFO - Running setup.py install for manifestparser 05:45:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:45:15 INFO - Running setup.py install for mozcrash 05:45:16 INFO - Running setup.py install for mozdebug 05:45:16 INFO - Running setup.py install for mozdevice 05:45:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Running setup.py install for mozfile 05:45:16 INFO - Running setup.py install for mozhttpd 05:45:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Running setup.py install for mozinfo 05:45:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Running setup.py install for mozInstall 05:45:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:45:16 INFO - Running setup.py install for mozleak 05:45:17 INFO - Running setup.py install for mozlog 05:45:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:45:17 INFO - Running setup.py install for moznetwork 05:45:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:45:17 INFO - Running setup.py install for mozprocess 05:45:17 INFO - Running setup.py install for mozprofile 05:45:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:45:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:45:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:45:17 INFO - Running setup.py install for mozrunner 05:45:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:45:17 INFO - Running setup.py install for mozscreenshot 05:45:18 INFO - Running setup.py install for moztest 05:45:18 INFO - Running setup.py install for mozversion 05:45:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:45:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:45:18 INFO - Cleaning up... 05:45:18 INFO - Return code: 0 05:45:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:18 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:18 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 05:45:18 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 05:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:18 INFO - 'HOME': '/Users/cltbld', 05:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:18 INFO - 'LOGNAME': 'cltbld', 05:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:18 INFO - 'MOZ_NO_REMOTE': '1', 05:45:18 INFO - 'NO_EM_RESTART': '1', 05:45:18 INFO - 'PAGER': '/bin/cat', 05:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:18 INFO - 'PWD': '/builds/slave/test', 05:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:18 INFO - 'SHELL': '/bin/bash', 05:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:18 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:18 INFO - 'USER': 'cltbld', 05:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:18 INFO - 'XPC_FLAGS': '0x0', 05:45:18 INFO - 'XPC_SERVICE_NAME': '0', 05:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:18 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-qurXkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:18 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)) 05:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:18 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-zWgFf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:18 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)) 05:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:18 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Yw7AjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:18 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-aynS1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-hUYzKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Uj2s4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-iIYIK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-kADl6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-4AqlUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-J0XDcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-_Hf8zL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:19 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)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-eTt97Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:19 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Xu66It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:20 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)) 05:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:20 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-1hlyvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:20 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)) 05:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:20 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-uVfvrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:20 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)) 05:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:20 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-TyYSjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:20 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)) 05:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:20 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-fRGOp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:20 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)) 05:45:20 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)) 05:45:20 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)) 05:45:20 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)) 05:45:20 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)) 05:45:20 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)) 05:45:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:20 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 05:45:20 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 05:45:20 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 05:45:20 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 05:45:20 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 05:45:20 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 05:45:23 INFO - Downloading blessings-1.6.tar.gz 05:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:45:23 INFO - Installing collected packages: blessings 05:45:23 INFO - Running setup.py install for blessings 05:45:23 INFO - Successfully installed blessings 05:45:23 INFO - Cleaning up... 05:45:23 INFO - Return code: 0 05:45:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:45:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:23 INFO - Reading from file tmpfile_stdout 05:45:23 INFO - Current package versions: 05:45:23 INFO - blessings == 1.6 05:45:23 INFO - blobuploader == 1.2.4 05:45:23 INFO - docopt == 0.6.1 05:45:23 INFO - functools32 == 3.2.3-2 05:45:23 INFO - jsonschema == 2.5.1 05:45:23 INFO - manifestparser == 1.1 05:45:23 INFO - mozInstall == 1.12 05:45:23 INFO - mozcrash == 1.0 05:45:23 INFO - mozdebug == 0.1 05:45:23 INFO - mozdevice == 0.49 05:45:23 INFO - mozfile == 1.2 05:45:23 INFO - mozhttpd == 0.7 05:45:23 INFO - mozinfo == 0.9 05:45:23 INFO - mozleak == 0.1 05:45:23 INFO - mozlog == 3.4 05:45:23 INFO - moznetwork == 0.27 05:45:23 INFO - mozprocess == 0.24 05:45:23 INFO - mozprofile == 0.28 05:45:23 INFO - mozrunner == 6.13 05:45:23 INFO - mozscreenshot == 0.1 05:45:23 INFO - mozsystemmonitor == 0.3 05:45:23 INFO - moztest == 0.8 05:45:23 INFO - mozversion == 1.4 05:45:23 INFO - psutil == 3.1.1 05:45:23 INFO - requests == 1.2.3 05:45:23 INFO - wsgiref == 0.1.2 05:45:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:23 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')]} 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 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')]} 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:23 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 05:45:23 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 05:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:23 INFO - 'HOME': '/Users/cltbld', 05:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:23 INFO - 'LOGNAME': 'cltbld', 05:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:23 INFO - 'MOZ_NO_REMOTE': '1', 05:45:23 INFO - 'NO_EM_RESTART': '1', 05:45:23 INFO - 'PAGER': '/bin/cat', 05:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:23 INFO - 'PWD': '/builds/slave/test', 05:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:23 INFO - 'SHELL': '/bin/bash', 05:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:23 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:23 INFO - 'USER': 'cltbld', 05:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:23 INFO - 'XPC_FLAGS': '0x0', 05:45:23 INFO - 'XPC_SERVICE_NAME': '0', 05:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 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)) 05:45:23 INFO - Cleaning up... 05:45:23 INFO - Return code: 0 05:45:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:45:23 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')]} 05:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 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')]} 05:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:23 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 0x106a2fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a32768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf10c78ba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, '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.j5eZlMUQJQ/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.vreamwBMJa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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 05:45:23 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 05:45:23 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 05:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:23 INFO - 'HOME': '/Users/cltbld', 05:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:23 INFO - 'LOGNAME': 'cltbld', 05:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:23 INFO - 'MOZ_NO_REMOTE': '1', 05:45:23 INFO - 'NO_EM_RESTART': '1', 05:45:23 INFO - 'PAGER': '/bin/cat', 05:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:23 INFO - 'PWD': '/builds/slave/test', 05:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:23 INFO - 'SHELL': '/bin/bash', 05:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:23 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:23 INFO - 'USER': 'cltbld', 05:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:23 INFO - 'XPC_FLAGS': '0x0', 05:45:23 INFO - 'XPC_SERVICE_NAME': '0', 05:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:45:24 INFO - Cleaning up... 05:45:24 INFO - Return code: 0 05:45:24 INFO - Running post-action listener: _resource_record_post_action 05:45:24 INFO - Running post-action listener: _start_resource_monitoring 05:45:24 INFO - Starting resource monitoring. 05:45:24 INFO - [mozharness: 2017-02-03 13:45:24.286703Z] Finished create-virtualenv step (success) 05:45:24 INFO - [mozharness: 2017-02-03 13:45:24.287578Z] Running install step. 05:45:24 INFO - Running pre-action listener: _resource_record_pre_action 05:45:24 INFO - Running main action method: install 05:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:24 INFO - Reading from file tmpfile_stdout 05:45:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:45:24 INFO - Reading from file tmpfile_stdout 05:45:24 INFO - Output received: 05:45:24 INFO - Usage: mozinstall [options] installer 05:45:24 INFO - Options: 05:45:24 INFO - -h, --help show this help message and exit 05:45:24 INFO - -d DEST, --destination=DEST 05:45:24 INFO - Directory to install application into. [default: 05:45:24 INFO - "/builds/slave/test"] 05:45:24 INFO - --app=APP Application being installed. [default: firefox] 05:45:24 INFO - mkdir: /builds/slave/test/build/application 05:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:45:46 INFO - Reading from file tmpfile_stdout 05:45:46 INFO - Output received: 05:45:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:45:46 INFO - Running post-action listener: _resource_record_post_action 05:45:46 INFO - [mozharness: 2017-02-03 13:45:46.693852Z] Finished install step (success) 05:45:46 INFO - [mozharness: 2017-02-03 13:45:46.693967Z] Running run-tests step. 05:45:46 INFO - Running pre-action listener: _resource_record_pre_action 05:45:46 INFO - Running main action method: run_tests 05:45:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:45:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:45:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:45:46 INFO - 2017-02-03 05:45:46.708 screenresolution[1625:8158] starting screenresolution argv=screenresolution get 05:45:46 INFO - 2017-02-03 05:45:46.711 screenresolution[1625:8158] Display 0: 1600x1200x32@60 05:45:46 INFO - 2017-02-03 05:45:46.716 screenresolution[1626:8160] starting screenresolution argv=screenresolution list 05:45:46 INFO - Available Modes on Display 0 05:45:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:45:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:45:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:45:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:45:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:45:46 INFO - Intel Iris: 05:45:46 INFO - Chipset Model: Intel Iris 05:45:46 INFO - Type: GPU 05:45:46 INFO - Bus: Built-In 05:45:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:45:46 INFO - Vendor: Intel (0x8086) 05:45:46 INFO - Device ID: 0x0a2e 05:45:46 INFO - Revision ID: 0x0009 05:45:46 INFO - Displays: 05:45:46 INFO - Display: 05:45:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:45:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:45:46 INFO - Display Serial Number: 1600x1200 60 05:45:46 INFO - Main Display: Yes 05:45:46 INFO - Mirror: Off 05:45:46 INFO - Online: Yes 05:45:46 INFO - Rotation: Supported 05:45:46 INFO - Return code: 0 05:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:45:46 INFO - Python 2.7.3 05:45:46 INFO - Return code: 0 05:45:46 INFO - grabbing minidump binary from tooltool 05:45: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')]} 05:45:46 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 0x10680dd30>, '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 0x106734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106802be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:45:46 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 05:45:46 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 05:45:46 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 05:45:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:45:47 INFO - Return code: 0 05:45:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:45:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:45:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:45:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:45:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:45:47 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/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 05:45:47 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/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --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 05:45:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j5eZlMUQJQ/Render', 05:45:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:47 INFO - 'HOME': '/Users/cltbld', 05:45:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:47 INFO - 'LOGNAME': 'cltbld', 05:45:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:45:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:47 INFO - 'MOZ_NO_REMOTE': '1', 05:45:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:45:47 INFO - 'NO_EM_RESTART': '1', 05:45:47 INFO - 'PAGER': '/bin/cat', 05:45:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:47 INFO - 'PWD': '/builds/slave/test', 05:45:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:45:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:47 INFO - 'SHELL': '/bin/bash', 05:45:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vreamwBMJa/Listeners', 05:45:47 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 05:45:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:47 INFO - 'USER': 'cltbld', 05:45:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:47 INFO - 'XPC_FLAGS': '0x0', 05:45:47 INFO - 'XPC_SERVICE_NAME': '0', 05:45:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:47 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/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--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 05:45:47 INFO - mozversion application_buildid: 20170203042710 05:45:47 INFO - mozversion application_changeset: 9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23 05:45:47 INFO - mozversion application_display_name: Nightly 05:45:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:45:47 INFO - mozversion application_name: Firefox 05:45:47 INFO - mozversion application_remotingname: firefox 05:45:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:45:47 INFO - mozversion application_vendor: Mozilla 05:45:47 INFO - mozversion application_version: 54.0a1 05:45:47 INFO - mozversion platform_buildid: 20170203042710 05:45:47 INFO - mozversion platform_changeset: 9f17a6fc04f9540ffe11d7ad4e22db5a37df6e23 05:45:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:45:47 INFO - mozversion platform_version: 54.0a1 05:45:47 INFO - using testdate: 1486129547 05:45:47 INFO - actual date: 1486129547 05:45:47 INFO - starting webserver on 'localhost:49235' 05:45:47 INFO - SUITE-START | Running 2 tests 05:45:47 INFO - TEST-START | dromaeo_css 05:45:47 INFO - operating with platform_type : mac_ 05:45:47 INFO - Initialising browser for dromaeo_css test... 05:45:47 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpwdWzh_/profile http://localhost:49235/getInfo.html) 05:45:49 INFO - PROCESS | 1636 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:45:49 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 05:45:49 INFO - PROCESS | 1636 | colorDepth:24 05:45:49 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 05:45:49 INFO - PROCESS | 1636 | __metrics 05:45:49 INFO - PROCESS | 1636 | 1486129549849 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (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:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:45:49 INFO - PROCESS | 1636 | 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" 05:45:49 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:45:50 INFO - TEST-INFO | 1636: exit 0 05:45:50 INFO - Browser initialized. 05:45:50 INFO - Running cycle 1/1 for dromaeo_css test... 05:45:50 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpwdWzh_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:02 INFO - PROCESS | 1639 | RSS: Main: 236425216 05:46:02 INFO - PROCESS | 1639 | 05:49:05 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 05:49:09 INFO - PROCESS | 1639 | RSS: Main: 520364032 05:49:09 INFO - PROCESS | 1639 | 05:50:52 INFO - PROCESS | 1639 | ************************* 05:50:52 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 05:50:52 INFO - PROCESS | 1639 | 05:50:52 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:50:52 INFO - PROCESS | 1639 | Full stack: 05:50:52 INFO - PROCESS | 1639 | ************************* 05:52:12 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 05:52:12 INFO - PROCESS | 1639 | RSS: Main: 375132160 05:52:12 INFO - PROCESS | 1639 | 05:55:15 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 05:55:15 INFO - PROCESS | 1639 | RSS: Main: 358481920 05:55:15 INFO - PROCESS | 1639 | 05:58:18 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 05:58:19 INFO - PROCESS | 1639 | RSS: Main: 350879744 05:58:19 INFO - PROCESS | 1639 | 06:01:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 06:01:22 INFO - PROCESS | 1639 | RSS: Main: 340332544 06:01:22 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 06:04:26 INFO - PROCESS | 1639 | RSS: Main: 384409600 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | __start_tp_report 06:04:26 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 06:04:26 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 06:04:26 INFO - PROCESS | 1639 | |i|pagename|runs| 06:04:26 INFO - PROCESS | 1639 | |0;dojo.html;3276.4102461556918;3293.538904553363;3299;3324.2852786650865;3351.899443016709;4900.828471003516;5447.863803404917;5499.422560631137;5604.187392828037;5656.377798442168;5535.723213839312;5604.467575580324;5629.155626656004;5649.180868774025;5675;5040.924386134211;5043.268726034723;5102.336696229503;5126.820561280356;5145.974270128644;5184.740762961856;5199.402068762103;5200.895982080375;5215.956808638269;5234.398044224906;4931.728754918478;4960.479149689303;4964.503549645043;5000.849974500789;5028.22062580297;5049.116404629175;5102.566281866046;5132.101882170605;5169.483051694838;5188.6367954243215;5034.748262586892;5039;5058.4435712071645;5085.79656813727;5117.181250999822;5473.507384335389;5498.3127109111365;5599.272094627713;5605.607607467479;5617.634853734535;6084.178635884144;6120.690942950886;6126.081087836816;6129.175413489954;6157;6192;6246.812595622138;6249.468795152417;6260.060990851409;6262.342454042351;5727.5131613812455;5735.4551317624755;5750.166225897264;5764.74058667361;5800.7679692812235;6078.787242446493;6144.310314663414;6174.876502469925;6232.563720539519;6276.184096067482;5797.942020579824;5835.153902684087;5855.882882342329;6060.303065147565;6120.60216085953;6064.545159113084;6078.6048906820915;6093.96953015241;6096.847578810565;6103.05402662588;5901.3508514063415;5980.611260268069;5985.072313791369;5986.3714309997695;6001.12983617373;6025.945459544562;6030.638161710312;6034.336223015464;6038.094285857156;6039.667818270037;4823.614110871122;4824.782884770194;4837.129316722953;4839.128956787812;4847.75761211938;4337.652987760972;4382.364557139197;4397.054633253866;4406.889827754268;4413.536578659195;5642.802501912413;5660.915086273709;5691.658500489983;5692.288463942007;5701.686407247558;5422.891542169214;5863.912041319383;6105.633661980295;6218.595791273643;6233.812985610439;5640.746166422521;5649.265595472562;5659.518940889988;5672;5708.457696518824;5352.437994010651;5360.463953604608;5361.865953351119;5370.382406023279;5373;6139.492138333381;6302.684865756785;6304.243490781136;6312.52656050798;6319.715612797343;6088.969555152196;6103.428585615474;6108.258823178673;6109.890546867989;6110.103144028831;5255.369355677343;6235.532335074979;6238.157848690369;6238.719257633326;6245.6252624842655;6118.174046503665;6142.713648198372;6144.126041557368;6173.919564076358;6179.134921111138;6223.315435302023;6224.813255602339;6225.190725205694;6236.594621349779;6266.68666566679;5201.349831271091;5779.7110144493445;5810.73851676684;6012.759489620366;6022.969885150546;5846.503047240862;5855.795047173328;5856.648601083778;5857.736401861841;5876.588638795414;5640.153976903333;5705.714714264187;5706.115552089433;5731.742071606871;5731.8567035824435;5856.648601083949;5857.882842343214;5857.9121313179485;5858.121281807763;5866.178734977021;5730.942690573208;5753.942460575508;5760.107183386582;5782.7686892523825;5804.709764511673 06:04:26 INFO - PROCESS | 1639 | |1;ext.html;477.58450148370923;490.2278910715624;508.59058457941353;511.9923201151986;527.4909712127799;38170.23659526808;40610.78167654971;40696.59303406969;42366.57633423665;42372.78813605928;40633.39049914254;41054.58945410545;41073.58926410735;41123;41123.971900702425;7932.881006784903;8173.141820108863;9158.175764181222;9397.295202859812;9418.387804792666;7996.240357166089;8099.352051835868;8135.959320203391;10873.238873278873;11082.889171108267;11066.557337706483;11101.167412444058;11115.333080015185;11142.331460112378;11172;11116.499757510892;11221.102311815035;11224.326540407601;11233;11246.268992515461;7921.683132674686;8097.311728503084;8133.877991830127;8172.387070969701;10876.347419154876;29559.704402956126;38264;38944.805275973726;38955.61044389548;40192;3868.9613103868883;3887.300285948528;3887.941680874789;3920.8431662733456;4019.3970904364287;29305.120846374644;29670.324177060204;29763.107106786832;29803.70196298031;29852.701472985427;3017.230606195406;3231.1922019495128;3291.8024918504966;3292.7859689120132;3300.455424854896;445923;451079;451256;452754.9999999967;452938;49235;50993.74503127535;51064.74467627638;58093;58453;71036.64481677559;72117.63941180366;72280;72286.63856680683;76211.61894190493;12082.275063496043;15364.692706145814;15579.454719084777;15792.21038948071;15903.284352203947;11947.880521194851;12050.51797928073;12141.69645758863;12148.271103733805;12166.513339466499;19724.309649162496;19792.90103549502;19845.106970186378;19870.801291987184;19889.60220795576;7505.348823258874;8390.916090839135;8406.327493800482;8411.789705257417;8422.30486244396;858.8754630578657;869.8042940338373;872.1292826886294;880.216607219575;899.3389858454032;29144.854275728485;29153.85423072871;29364.412711745645;29904.102876913727;30102;1056.4328681304503;1083.0631503749357;1083.8049151152711;1094.5020015892749;1178.4545381974892;14686.779698304534;15096.471623493344;15192.544223673307;15363.46227882041;15401.691966160839;609525;612802.9359853172;613049;613118;613238;57415.42584574184;57752.422475775544;57860.4213957855;57957.420425796045;57957.710211449514;1376.8554301798367;1395.3162950154351;1439.251589173603;1441.2001339256603;1445.6674964758165;27761.722382775915;27869;28352.60534617138;29072.273193169494;29309.7069029307;49404;49780;49837.252441214136;49881.75059124681;49976.500234997184;425574;428034;428943;429155;429556;39748.60251397449;40309.59690403176;41031.20407122637;41205.381919270636;41251;41246.175076498905;45590.31614525851;45829.08341833078;45921.31118033299;46231;37150.44274335941;37238;37391.62608373881;38186.427203592524;38202.42696359501;33620.99137025893;35117.47323790196;36830.44754328741;37832.810835945646;37983;33851.66148338583;33977;34593;34634.82682586571;34686.479702803976;23228.41928951722;28960;29810;31067;31222;23297.068117275117;23310.417239569146;23604;23654.763452365256;23733 06:04:26 INFO - PROCESS | 1639 | |2;jquery.html;369.8132443116226;394.8025987006497;395.74672209785723;406.6380920980327;424.21520187652845;14099.929500352497;15006.099634021954;18038.4588462346;20859;21288.254911078115;20890.955452227405;21164.8941755291;21194.788052119475;21439.57120857582;21494.8505149485;5849.912251316234;5872.471477567018;6017.368176341487;6063.939360606381;6153.784617538365;3841.5966323536045;5907.645541267516;6014.548908831834;6017.338092809788;6021;4006.8597599084037;6285.874282514347;6555.475561955032;6584.9012264816065;6677.332266773308;6420.101185833987;6596.901046484307;6649.5677780944325;6676.09872667191;6679.499037572202;5836.358000619928;5840.737166827503;5841.591088623798;5848;5848.707564621752;24327.635085473732;25368;25378.82764654418;25398.492030159294;25658.87170564154;2437.463757973243;2691.3809823740453;2707.187843646918;2738.438620082878;2743.3004583831193;15356.923215383964;15369.077855328716;15390.615234619112;15689.52931412048;16338.83661163397;1784.839364457192;1818.6453641539906;1831.9749402455595;1839.9724004139948;1858.962820743591;125201;126825;127206;128997.42005159844;129426;11450.854914508649;11467.770644587228;11605.6518304451;11783;11808.933436648553;14802.481913132988;14816.666250093858;14858.182799945891;14862.777058344134;14897.553073407815;8244.670213191404;8506.617202225914;8514.361422893307;8614.741557753277;8692.913385826772;8040;8288.295494882881;8327.250547450758;8446.915530844613;8521.44610600309;7074.434045276365;7147.642617869085;7202.855942881113;7203.603801790847;7212.3869471094495;1499.5201535508534;1916.3101283537915;1943.1061711612533;1949.1813438355925;1952.6094781043848;391.2156126965416;435.7211384713786;1010.777628921636;1043.436544266103;1059.426751274357;11268;11868.406579670982;12235.959943404732;12240.938795305967;12319.69200769988;447.65754198038525;449.3147949377226;455.2988397867313;460.61768731953066;474.6795912758943;6016.879662406727;10634.20243481742;11755.177137600285;12089.335086570323;12340.382980850922;599719;662705;662727;670130;679542.3189970545;88661.11338886658;148688;149162.25418872837;152394.28451439424;155575.3318501117;1089.8746644135867;1094.534822700365;1135.517405102828;1136.3182090745486;1140.8516892803882;19467.805321947166;19616;19847.503812404804;19875.701864472332;19892.90053549723;50664.98670026653;51449.19927802509;52526.42420727384;57322;58059.838803222854;71041.57916841737;78908.60545697235;83357;85972;87698;7110.288971103055;7255.09311336083;7378.188399276182;7393.741219057307;7530.397568194641;9749.415035097916;11922.821157682814;12218.572349967708;12441;13281.535146269835;10938.12495000414;11030.359981206022;11041;11449.94275028653;11574.305541667527;9400.81198376015;10454.581816727246;11030.724231894266;11034.668959931216;11366.033887119356;11497.962881371821;11738.826117388193;11782.528698852293;11819.172657913863;11820.940895295469;6967.1291088613925;7210.783676489714;7381.224971377928;7491.213422590549;7572.864070389265;9969.152622027208;11063.336199828036;11089.833652495057;11327.773444530898;11423.457833192746 06:04:26 INFO - PROCESS | 1639 | |3;mootools.html;1414.879735222506;1433.655922578581;1437.813084299041;1441.675622984828;1442.704245629646;8077.717279895198;8518.403711740166;8539.274161696256;8539.658413663461;8546.40175187737;8117.350611951045;8240.95879520603;8313.750587482386;8326.625301861415;8491.490510569354;2143.2820005298167;2148.1407437025196;2165.220520612578;2190.9624049633835;2193.4297082758517;2089.874607523546;2180.2587120379067;2183.1049269799387;2185.311626837549;2189.7262842144733;1872.7939926608062;1935.874168179071;1946.211784227383;2114.8942552872372;2186.420598541388;2175.9456013599706;2195.3194509701884;2196.198387588535;2200.163937703662;2201.7908298711677;1892.5174080609356;2144.1744928202756;2170.5550362175873;2171.6742488626833;2180.073468775764;7570.962145189294;7712.57580833054;7714.267144621252;7719;7724.948645285081;4891.951080489185;5029.119904016819;5041.521055499722;5094.235864620299;5094.388673359221;3311.768176227665;3383.8477268522975;3409.09658940381;3458.6195518492946;3468.236987862666;1204.2353105777806;1213.6359092272282;1228.3735294999526;1247.819066235391;1262.0219330019218;10647.520861561248;10815.405152716676;10821.134309255242;10849.294795838245;10855.24013319068;4103.425520427142;4353.455818022747;4424.955750442455;4433.401490798766;4450.6884518083425;8698.608562614698;8703.042665306812;8708.869366959501;8716.825663486821;8733.432326898732;5051.166557518042;5056.9494305057215;5057.6459647824295;5058.620603454756;5064.5441910228255;4549.863504094882;4687.367205427292;4725.706385293787;4727.290906364073;4777.4983626718695;3431.7083047940705;3444.3800115979366;3465.7054150396993;3519.964800352015;3520.436730123168;958.5734348215117;1089.0451639307462;1094.6114129484079;1097.983530247047;1103.768208676187;763.2672634271162;810.4772421787914;895.4492986813193;908.6728777640044;946.4321407155652;5183.870403239949;5670.688112153789;5769.048107062372;5781.335146458127;6056.454919057218;950.9952450237843;958.7746879483316;985.6500942165571;987.4618333008527;1028.5063169678447;3680.4479328100997;3690.870819521304;3767.095896984704;3816.9809150954625;3851.9614803852164;9051.773705657411;9418.623255069857;9507.477088760046;9706;9903.554340054714;9979.700608981742;9981.301308908307;10014.24893133018;10014.49927503622;10018.29871908974;6958.060661810692;7268.781936541912;7486.363659089038;7642.4650274480255;7746.651400687096;5026.698398096126;5219.373675158853;5468.152436372368;5629.296337957755;5785.82642520725;9021.32340074484;9108.271338293052;9209;9243.352965292364;9252.398594091363;9268.768280793034;9269.860952085859;9309.487978161265;9378;9412.529373531159;6990.300969902969;7168.462365322517;7176.533525320838;7178.641067946477;7194.96402518005;7701.576413297323;7719.382449404146;7734.92265077342;7780.688772449265;7810.062792464941;7003.124609423822;7037.542559733601;7056.259092795388;7122.85754284901;7135.6432178391915;6602.801915942529;6629.403353698191;6651.06885036105;6671.266160722414;6684.933150668431;6331.9050214247745;6429.7428102875365;6434.453071488976;6441.066045423481;6444.130042444398;4854.296127061627;4908.190148625436;4982.526659967298;5002.899942001212;5169.793208271627;4546.590806827401;4930.926036109389;5043.520865517917;5081.212412076134;5127.435982042047 06:04:26 INFO - PROCESS | 1639 | |4;prototype.html;338.7544030577831;359.2312451354103;366.74144727966785;371.12784955354937;372.61248301766165;4480.843170489034;4498.820047198116;5227.712475813829;5258.710770907599;5347.705876176804;5024.743814046488;5219.608529360304;5269.525742683158;5275.6043296752805;5299.60252981027;3551.7691350062164;3882.9611703883024;4027.395890616416;4042.4744783178144;4059.9594004060023;4053.756774593534;4057.4116753070934;4067.0717083699687;4071.491063617048;4088.9591104089022;3858.9002134391703;3934.350832112698;3946.7237293389476;4017.698672599552;4032.5765794591584;3939.034936440576;3940.5862384449792;3944.9013774655577;3964.405339199114;3977.3238549446664;3055.572219889217;3451.7583769135917;3452.94685121038;3477.5479187705437;3485.5468789057513;4308.741475511448;4711.057788442326;5143.376877792315;5199.53204211619;5225.869353266161;3675.7059435245114;3852.6339997700175;4294.892627684301;4583.404157459542;4632.606228470584;4851.175300198975;5486.780528778844;5531.806386776484;5546.947629377116;5574.554035677136;3142.6385965614018;3264.9183770405693;3567.5940213835893;3587.856485740567;3838.5201849768778;5104.412992505872;5471.7565798023015;5731.570132240098;5736.959411511905;5811.535077193815;3317.102404674498;3464.930701385958;3866.96133038666;3878.8836334909997;3916.667083297895;3983.342748446532;3990.2219067281603;3991.9201615967518;4000.759954402687;4007.6593489553134;3870.109874728772;4334.306510958231;4338.869833904988;4460.598546130864;4682.976585117052;4995.600351971906;5627.915581266284;5641.407652196543;5645;5650.378458369576;1079.3523885668535;1258.4714419943646;1260.8148340559844;1270.4156088199347;1276.3618190904547;795.8778122846874;804.7384600005022;871.9215270625674;872.1104473437059;874.881890944727;4125.278076336629;4212.431321771583;4918.573613652073;4953.356063711766;5014.0695832104175;4924.75376231187;5252.05463016653;5478.260434841245;5497.835064948058;5513.421090785409;4064.573219811877;4478.57453541913;4562.634989200856;4806.351142595775;4970.136198925403;2394.772496612819;2475.4553998120386;2557.3350928758655;2809.634747482814;2842.928926776806;4891.755412229374;5135.640505164677;5379.596530260246;5592.692401917852;5620.297462817148;4681.401821988761;4824.697331720416;5171.689698618095;5471.398146203915;5540.282512421082;4480.887977800516;5102.984321193182;5202.88138050321;5401.081816091274;5613.354464236665;5673.404292549388;5683.82239054303;5687.459691329483;5705.600607957404;5777.653340799566;4842.806287748451;5292.841214763564;5600.963821693;5622.32532096151;5668.744906479136;5473.648449482591;5912.70436478183;5937.435943585352;5950.375210602998;5970.671613061149;3300.372929143455;3823.5602905666206;3872.7095467839463;3879.86420475282;3898.317794386028;5313.415524292402;5697.572682048946;5720.885582288248;5741.45456181662;5747.281589801275;4518.322251662145;5586.078297081099;5601.2076135637835;5613.831585052455;5617.32592088952;5184.507471790174;5567.69377684215;5571.582131340247;5573.526250268773;5573.637713548606;4296.9902073013445;4746.715197088186;5061;5414.539764119936;5494.340679118532;5274.314339135888;5487.807926722546;5502.80740174092;5507.310102231434;5513.917291240555;4781.402324709411;5269.868253293698;5401.162819762943;5459.808906688247;5501.679596896796 06:04:26 INFO - PROCESS | 1639 | |5;yui.html;717.6519388096775;753.5290443472826;765.5980610179655;772.7313464047081;850.9702160424384;14498.710025799492;14597.416103355867;14866;14954.476593319236;15034.398624055038;13554.93222533886;13867.653308667288;13873.306334683275;13993.650158746037;14012.369443375048;876.7501262140275;889.4129874282975;894.0344428017758;894.472261365794;894.5661354243219;858.0947100808644;884.7213127864734;886.2201262888649;893.5576889439736;894.1773568317132;897.7710683775628;901.7339884733987;936.4708939449189;939.5208443693731;941.1576638908207;934.9065093490631;935.8596210568435;936.1106948399048;936.4053825820653;938.7934654376027;697.8953157026436;698.6471831724997;698.671624336561;699.5732603112098;699.7690762048512;14705.117692938458;14874.181919994397;14908.105513669214;14922.62693432662;15156.090634561851;13785.103968242032;14225.573232803032;14226.28868556568;14227.35976881043;14459.56621301352;11732.706682332924;11753.588624398106;11799.056075513938;11825.11311651621;11925.942538187419;3343.882964096245;3486.459598762196;3645.2162785001087;3786.9964459418275;3799.8670046548236;55194;56982;77038.6148069256;95389.52305238499;103686.48156759243;10613.204009699304;10703.143748500257;10810.324669484753;11055.170862185369;11173.385463799568;18311.450656480327;18945;21964.780352196594;22311;22329.776702233095;7550.3582195514;7619.199984001711;7765.53406795594;7769.922300777033;7882.290593846581;4069.206504731608;4153.273177193979;4154.563770804022;4157.189348077157;4167.74993500391;3466.3587236361354;3520.2431477232517;3550.218951830593;3550.8047057411572;3618.746687731886;718.4755128755933;720.5388551327155;721.4949535325293;736.4182295986218;736.7973807202955;674.5581644023173;679.8742232686968;698.472653146881;713.3045280851129;719.6869361827622;9865.704028879145;9908.455034973002;10300.639477618248;10414.895851041545;10494.47527623631;672.1262358933465;673.8483841135705;680.2415306932735;694.5173104692292;695.8886578147473;4643.419572553431;4965.935199629197;4967.3294105296045;4970.707616019861;4990.683461280494;85116;91931.0806891936;99902.50048749709;102893.94212115716;103215.45176822353;74839;84904.72642910361;85697.14302857016;86086.56956715176;87869.68195477073;59783;71405.64297178584;73775;75248;77080.2291977073;9258;9603.461282892895;9668;9698.563564639468;9701.805963880543;45488;46474.76762616165;48044.03911921672;49084.01831963269;51031.97936041332;61814;62708.059379110266;63026.68486657537;63058.48710424034;63701.4074648119;12790.168639038433;12905.483780648668;12960.740785184054;13441.059125861097;13449.991250656436;14471.565853024425;14529.055611385225;14577.85422145765;14682.486112985995;15433.459828905934;13491.392887320291;13503.527376541773;13635.522756703467;14032.579022628928;14243.145411275356;13602.319884005958;13612.319384030561;13622.659433514242;13777.724445511234;14212.005159638726;12901.806472902726;13874.861251387762;13930.791038134234;13937;14046.227457489937;10791.784164316827;11187.832182517432;11224.719382015515;11645.359505226956;11680.47437865315;10199;10681.27149474189;10810.729731756453;10824.296420732628;10902.836457452986 06:04:26 INFO - PROCESS | 1639 | __end_tp_report 06:04:26 INFO - PROCESS | 1639 | __start_cc_report 06:04:26 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,3246 06:04:26 INFO - PROCESS | 1639 | __end_cc_report 06:04:26 INFO - PROCESS | 1639 | __startTimestamp1486130666537__endTimestamp 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | ------- Summary: start ------- 06:04:26 INFO - PROCESS | 1639 | Number of tests: 6 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5617.26 Median:5753.94 stddev:625.51 (10.9%) stddev-sans-first:599.87 06:04:26 INFO - PROCESS | 1639 | Values: 3276.4 3293.5 3299.0 3324.3 3351.9 4900.8 5447.9 5499.4 5604.2 5656.4 5535.7 5604.5 5629.2 5649.2 5675.0 5040.9 5043.3 5102.3 5126.8 5146.0 5184.7 5199.4 5200.9 5216.0 5234.4 4931.7 4960.5 4964.5 5000.8 5028.2 5049.1 5102.6 5132.1 5169.5 5188.6 5034.7 5039.0 5058.4 5085.8 5117.2 5473.5 5498.3 5599.3 5605.6 5617.6 6084.2 6120.7 6126.1 6129.2 6157.0 6192.0 6246.8 6249.5 6260.1 6262.3 5727.5 5735.5 5750.2 5764.7 5800.8 6078.8 6144.3 6174.9 6232.6 6276.2 5797.9 5835.2 5855.9 6060.3 6120.6 6064.5 6078.6 6094.0 6096.8 6103.1 5901.4 5980.6 5985.1 5986.4 6001.1 6025.9 6030.6 6034.3 6038.1 6039.7 4823.6 4824.8 4837.1 4839.1 4847.8 4337.7 4382.4 4397.1 4406.9 4413.5 5642.8 5660.9 5691.7 5692.3 5701.7 5422.9 5863.9 6105.6 6218.6 6233.8 5640.7 5649.3 5659.5 5672.0 5708.5 5352.4 5360.5 5361.9 5370.4 5373.0 6139.5 6302.7 6304.2 6312.5 6319.7 6089.0 6103.4 6108.3 6109.9 6110.1 5255.4 6235.5 6238.2 6238.7 6245.6 6118.2 6142.7 6144.1 6173.9 6179.1 6223.3 6224.8 6225.2 6236.6 6266.7 5201.3 5779.7 5810.7 6012.8 6023.0 5846.5 5855.8 5856.6 5857.7 5876.6 5640.2 5705.7 5706.1 5731.7 5731.9 5856.6 5857.9 5857.9 5858.1 5866.2 5730.9 5753.9 5760.1 5782.8 5804.7 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:64128.63 Median:21066.48 stddev:134271.62 (637.4%) stddev-sans-first:134563.24 06:04:26 INFO - PROCESS | 1639 | Values: 477.6 490.2 508.6 512.0 527.5 38170.2 40610.8 40696.6 42366.6 42372.8 40633.4 41054.6 41073.6 41123.0 41124.0 7932.9 8173.1 9158.2 9397.3 9418.4 7996.2 8099.4 8136.0 10873.2 11082.9 11066.6 11101.2 11115.3 11142.3 11172.0 11116.5 11221.1 11224.3 11233.0 11246.3 7921.7 8097.3 8133.9 8172.4 10876.3 29559.7 38264.0 38944.8 38955.6 40192.0 3869.0 3887.3 3887.9 3920.8 4019.4 29305.1 29670.3 29763.1 29803.7 29852.7 3017.2 3231.2 3291.8 3292.8 3300.5 445923.0 451079.0 451256.0 452755.0 452938.0 49235.0 50993.7 51064.7 58093.0 58453.0 71036.6 72117.6 72280.0 72286.6 76211.6 12082.3 15364.7 15579.5 15792.2 15903.3 11947.9 12050.5 12141.7 12148.3 12166.5 19724.3 19792.9 19845.1 19870.8 19889.6 7505.3 8390.9 8406.3 8411.8 8422.3 858.9 869.8 872.1 880.2 899.3 29144.9 29153.9 29364.4 29904.1 30102.0 1056.4 1083.1 1083.8 1094.5 1178.5 14686.8 15096.5 15192.5 15363.5 15401.7 609525.0 612802.9 613049.0 613118.0 613238.0 57415.4 57752.4 57860.4 57957.4 57957.7 1376.9 1395.3 1439.3 1441.2 1445.7 27761.7 27869.0 28352.6 29072.3 29309.7 49404.0 49780.0 49837.3 49881.8 49976.5 425574.0 428034.0 428943.0 429155.0 429556.0 39748.6 40309.6 41031.2 41205.4 41251.0 41246.2 45590.3 45829.1 45921.3 46231.0 37150.4 37238.0 37391.6 38186.4 38202.4 33621.0 35117.5 36830.4 37832.8 37983.0 33851.7 33977.0 34593.0 34634.8 34686.5 23228.4 28960.0 29810.0 31067.0 31222.0 23297.1 23310.4 23604.0 23654.8 23733.0 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:37216.49 Median:25373.41 stddev:109532.67 (431.7%) stddev-sans-first:109804.99 06:04:26 INFO - PROCESS | 1639 | Values: 369.8 394.8 395.7 406.6 424.2 14099.9 15006.1 18038.5 20859.0 21288.3 20891.0 21164.9 21194.8 21439.6 21494.9 5849.9 5872.5 6017.4 6063.9 6153.8 3841.6 5907.6 6014.5 6017.3 6021.0 4006.9 6285.9 6555.5 6584.9 6677.3 6420.1 6596.9 6649.6 6676.1 6679.5 5836.4 5840.7 5841.6 5848.0 5848.7 24327.6 25368.0 25378.8 25398.5 25658.9 2437.5 2691.4 2707.2 2738.4 2743.3 15356.9 15369.1 15390.6 15689.5 16338.8 1784.8 1818.6 1832.0 1840.0 1859.0 125201.0 126825.0 127206.0 128997.4 129426.0 11450.9 11467.8 11605.7 11783.0 11808.9 14802.5 14816.7 14858.2 14862.8 14897.6 8244.7 8506.6 8514.4 8614.7 8692.9 8040.0 8288.3 8327.3 8446.9 8521.4 7074.4 7147.6 7202.9 7203.6 7212.4 1499.5 1916.3 1943.1 1949.2 1952.6 391.2 435.7 1010.8 1043.4 1059.4 11268.0 11868.4 12236.0 12240.9 12319.7 447.7 449.3 455.3 460.6 474.7 6016.9 10634.2 11755.2 12089.3 12340.4 599719.0 662705.0 662727.0 670130.0 679542.3 88661.1 148688.0 149162.3 152394.3 155575.3 1089.9 1094.5 1135.5 1136.3 1140.9 19467.8 19616.0 19847.5 19875.7 19892.9 50665.0 51449.2 52526.4 57322.0 58059.8 71041.6 78908.6 83357.0 85972.0 87698.0 7110.3 7255.1 7378.2 7393.7 7530.4 9749.4 11922.8 12218.6 12441.0 13281.5 10938.1 11030.4 11041.0 11449.9 11574.3 9400.8 10454.6 11030.7 11034.7 11366.0 11498.0 11738.8 11782.5 11819.2 11820.9 6967.1 7210.8 7381.2 7491.2 7572.9 9969.2 11063.3 11089.8 11327.8 11423.5 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5318.33 Median:5094.31 stddev:2929.17 (57.5%) stddev-sans-first:2922.70 06:04:26 INFO - PROCESS | 1639 | Values: 1414.9 1433.7 1437.8 1441.7 1442.7 8077.7 8518.4 8539.3 8539.7 8546.4 8117.4 8241.0 8313.8 8326.6 8491.5 2143.3 2148.1 2165.2 2191.0 2193.4 2089.9 2180.3 2183.1 2185.3 2189.7 1872.8 1935.9 1946.2 2114.9 2186.4 2175.9 2195.3 2196.2 2200.2 2201.8 1892.5 2144.2 2170.6 2171.7 2180.1 7571.0 7712.6 7714.3 7719.0 7724.9 4892.0 5029.1 5041.5 5094.2 5094.4 3311.8 3383.8 3409.1 3458.6 3468.2 1204.2 1213.6 1228.4 1247.8 1262.0 10647.5 10815.4 10821.1 10849.3 10855.2 4103.4 4353.5 4425.0 4433.4 4450.7 8698.6 8703.0 8708.9 8716.8 8733.4 5051.2 5056.9 5057.6 5058.6 5064.5 4549.9 4687.4 4725.7 4727.3 4777.5 3431.7 3444.4 3465.7 3520.0 3520.4 958.6 1089.0 1094.6 1098.0 1103.8 763.3 810.5 895.4 908.7 946.4 5183.9 5670.7 5769.0 5781.3 6056.5 951.0 958.8 985.7 987.5 1028.5 3680.4 3690.9 3767.1 3817.0 3852.0 9051.8 9418.6 9507.5 9706.0 9903.6 9979.7 9981.3 10014.2 10014.5 10018.3 6958.1 7268.8 7486.4 7642.5 7746.7 5026.7 5219.4 5468.2 5629.3 5785.8 9021.3 9108.3 9209.0 9243.4 9252.4 9268.8 9269.9 9309.5 9378.0 9412.5 6990.3 7168.5 7176.5 7178.6 7195.0 7701.6 7719.4 7734.9 7780.7 7810.1 7003.1 7037.5 7056.3 7122.9 7135.6 6602.8 6629.4 6651.1 6671.3 6684.9 6331.9 6429.7 6434.5 6441.1 6444.1 4854.3 4908.2 4982.5 5002.9 5169.8 4546.6 4930.9 5043.5 5081.2 5127.4 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4410.46 Median:4921.66 stddev:1366.10 (27.8%) stddev-sans-first:1335.31 06:04:26 INFO - PROCESS | 1639 | Values: 338.8 359.2 366.7 371.1 372.6 4480.8 4498.8 5227.7 5258.7 5347.7 5024.7 5219.6 5269.5 5275.6 5299.6 3551.8 3883.0 4027.4 4042.5 4060.0 4053.8 4057.4 4067.1 4071.5 4089.0 3858.9 3934.4 3946.7 4017.7 4032.6 3939.0 3940.6 3944.9 3964.4 3977.3 3055.6 3451.8 3452.9 3477.5 3485.5 4308.7 4711.1 5143.4 5199.5 5225.9 3675.7 3852.6 4294.9 4583.4 4632.6 4851.2 5486.8 5531.8 5546.9 5574.6 3142.6 3264.9 3567.6 3587.9 3838.5 5104.4 5471.8 5731.6 5737.0 5811.5 3317.1 3464.9 3867.0 3878.9 3916.7 3983.3 3990.2 3991.9 4000.8 4007.7 3870.1 4334.3 4338.9 4460.6 4683.0 4995.6 5627.9 5641.4 5645.0 5650.4 1079.4 1258.5 1260.8 1270.4 1276.4 795.9 804.7 871.9 872.1 874.9 4125.3 4212.4 4918.6 4953.4 5014.1 4924.8 5252.1 5478.3 5497.8 5513.4 4064.6 4478.6 4562.6 4806.4 4970.1 2394.8 2475.5 2557.3 2809.6 2842.9 4891.8 5135.6 5379.6 5592.7 5620.3 4681.4 4824.7 5171.7 5471.4 5540.3 4480.9 5103.0 5202.9 5401.1 5613.4 5673.4 5683.8 5687.5 5705.6 5777.7 4842.8 5292.8 5601.0 5622.3 5668.7 5473.6 5912.7 5937.4 5950.4 5970.7 3300.4 3823.6 3872.7 3879.9 3898.3 5313.4 5697.6 5720.9 5741.5 5747.3 4518.3 5586.1 5601.2 5613.8 5617.3 5184.5 5567.7 5571.6 5573.5 5573.6 4297.0 4746.7 5061.0 5414.5 5494.3 5274.3 5487.8 5502.8 5507.3 5513.9 4781.4 5269.9 5401.2 5459.8 5501.7 06:04:26 INFO - PROCESS | 1639 | 06:04:26 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:18992.48 Median:24827.87 stddev:26070.13 (105.0%) stddev-sans-first:26107.14 06:04:26 INFO - PROCESS | 1639 | Values: 717.7 753.5 765.6 772.7 851.0 14498.7 14597.4 14866.0 14954.5 15034.4 13554.9 13867.7 13873.3 13993.7 14012.4 876.8 889.4 894.0 894.5 894.6 858.1 884.7 886.2 893.6 894.2 897.8 901.7 936.5 939.5 941.2 934.9 935.9 936.1 936.4 938.8 697.9 698.6 698.7 699.6 699.8 14705.1 14874.2 14908.1 14922.6 15156.1 13785.1 14225.6 14226.3 14227.4 14459.6 11732.7 11753.6 11799.1 11825.1 11925.9 3343.9 3486.5 3645.2 3787.0 3799.9 55194.0 56982.0 77038.6 95389.5 103686.5 10613.2 10703.1 10810.3 11055.2 11173.4 18311.5 18945.0 21964.8 22311.0 22329.8 7550.4 7619.2 7765.5 7769.9 7882.3 4069.2 4153.3 4154.6 4157.2 4167.7 3466.4 3520.2 3550.2 3550.8 3618.7 718.5 720.5 721.5 736.4 736.8 674.6 679.9 698.5 713.3 719.7 9865.7 9908.5 10300.6 10414.9 10494.5 672.1 673.8 680.2 694.5 695.9 4643.4 4965.9 4967.3 4970.7 4990.7 85116.0 91931.1 99902.5 102893.9 103215.5 74839.0 84904.7 85697.1 86086.6 87869.7 59783.0 71405.6 73775.0 75248.0 77080.2 9258.0 9603.5 9668.0 9698.6 9701.8 45488.0 46474.8 48044.0 49084.0 51032.0 61814.0 62708.1 63026.7 63058.5 63701.4 12790.2 12905.5 12960.7 13441.1 13450.0 14471.6 14529.1 14577.9 14682.5 15433.5 13491.4 13503.5 13635.5 14032.6 14243.1 13602.3 13612.3 13622.7 13777.7 14212.0 12901.8 13874.9 13930.8 13937.0 14046.2 10791.8 11187.8 11224.7 11645.4 11680.5 10199.0 10681.3 10810.7 10824.3 10902.8 06:04:26 INFO - PROCESS | 1639 | -------- Summary: end -------- 06:04:26 INFO - PROCESS | 1639 | 06:04:27 INFO - TEST-INFO | 1639: exit 0 06:04:27 INFO - TEST-OK | dromaeo_css | took 1119902ms 06:04:27 INFO - TEST-START | kraken 06:04:27 INFO - operating with platform_type : mac_ 06:04:27 INFO - Initialising browser for kraken test... 06:04:27 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpGVnz0T/profile http://localhost:49235/getInfo.html) 06:04:29 INFO - PROCESS | 1645 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:04:29 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 06:04:29 INFO - PROCESS | 1645 | colorDepth:24 06:04:29 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 06:04:29 INFO - PROCESS | 1645 | __metrics 06:04:29 INFO - PROCESS | 1645 | 1486130669624 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (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:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 06:04:29 INFO - PROCESS | 1645 | 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" 06:04:29 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:04:30 INFO - TEST-INFO | 1645: exit 0 06:04:30 INFO - Browser initialized. 06:04:30 INFO - Running cycle 1/1 for kraken test... 06:04:30 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpGVnz0T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:42 INFO - PROCESS | 1646 | RSS: Main: 236249088 06:04:42 INFO - PROCESS | 1646 | 06:04:45 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 06:04:46 INFO - PROCESS | 1646 | RSS: Main: 289067008 06:04:46 INFO - PROCESS | 1646 | 06:04:48 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 06:04:49 INFO - PROCESS | 1646 | RSS: Main: 291454976 06:04:49 INFO - PROCESS | 1646 | 06:04:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 06:04:53 INFO - PROCESS | 1646 | RSS: Main: 380264448 06:04:53 INFO - PROCESS | 1646 | 06:04:55 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 06:04:55 INFO - PROCESS | 1646 | RSS: Main: 284082176 06:04:55 INFO - PROCESS | 1646 | 06:04:57 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:04:58 INFO - PROCESS | 1646 | RSS: Main: 287358976 06:04:58 INFO - PROCESS | 1646 | 06:05:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:01 INFO - PROCESS | 1646 | RSS: Main: 330317824 06:05:01 INFO - PROCESS | 1646 | 06:05:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 06:05:05 INFO - PROCESS | 1646 | RSS: Main: 332075008 06:05:05 INFO - PROCESS | 1646 | 06:05:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 06:05:08 INFO - PROCESS | 1646 | RSS: Main: 328171520 06:05:08 INFO - PROCESS | 1646 | 06:05:10 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:05:10 INFO - PROCESS | 1646 | RSS: Main: 305172480 06:05:10 INFO - PROCESS | 1646 | 06:05:12 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:05:13 INFO - PROCESS | 1646 | RSS: Main: 310087680 06:05:13 INFO - PROCESS | 1646 | 06:05:15 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:05:16 INFO - PROCESS | 1646 | RSS: Main: 311226368 06:05:16 INFO - PROCESS | 1646 | 06:05:19 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:05:19 INFO - PROCESS | 1646 | RSS: Main: 329306112 06:05:19 INFO - PROCESS | 1646 | 06:05:22 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:05:23 INFO - PROCESS | 1646 | RSS: Main: 323559424 06:05:23 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 06:05:25 INFO - PROCESS | 1646 | RSS: Main: 368078848 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | __start_tp_report 06:05:25 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 06:05:25 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 06:05:25 INFO - PROCESS | 1646 | |i|pagename|runs| 06:05:25 INFO - PROCESS | 1646 | |0;ai-astar;110;113;113;113;111;113;111;113;112;113 06:05:25 INFO - PROCESS | 1646 | |1;audio-beat-detection;141;109;110;145;110;110;109;110;132;109 06:05:25 INFO - PROCESS | 1646 | |2;audio-dft;186;184;183;184;182;185;182;184;184;184 06:05:25 INFO - PROCESS | 1646 | |3;audio-fft;78;58;59;86;59;57;59;58;75;58 06:05:25 INFO - PROCESS | 1646 | |4;audio-oscillator;90;73;86;69;70;69;85;69;70;84 06:05:25 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;87;86;88;85;89;85;89;86;89;84 06:05:25 INFO - PROCESS | 1646 | |6;imaging-darkroom;95;95;94;94;94;95;106;150;95;95 06:05:25 INFO - PROCESS | 1646 | |7;imaging-desaturate;88;87;85;85;86;85;87;85;87;83 06:05:25 INFO - PROCESS | 1646 | |8;json-parse-financial;58;58;56;58;58;59;58;60;59;57 06:05:25 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;66;76;80;77;74;70;71;72;77;84 06:05:25 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;88;85;88;86;90;85;86;91;86;77 06:05:25 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;139;137;140;138;137;140;139;138;180;142 06:05:25 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;170;174;172;172;176;171;170;170;171;173 06:05:25 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;66;68;68;68;67;66;66;65;66;66 06:05:25 INFO - PROCESS | 1646 | __end_tp_report 06:05:25 INFO - PROCESS | 1646 | __start_cc_report 06:05:25 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2078 06:05:25 INFO - PROCESS | 1646 | __end_cc_report 06:05:25 INFO - PROCESS | 1646 | __startTimestamp1486130725441__endTimestamp 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | ------- Summary: start ------- 06:05:25 INFO - PROCESS | 1646 | Number of tests: 14 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:1.14 (1.0%) stddev-sans-first:0.88 06:05:25 INFO - PROCESS | 1646 | Values: 110.0 113.0 113.0 113.0 111.0 113.0 111.0 113.0 112.0 113.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:110.00 stddev:14.72 (13.4%) stddev-sans-first:13.17 06:05:25 INFO - PROCESS | 1646 | Values: 141.0 109.0 110.0 145.0 110.0 110.0 109.0 110.0 132.0 109.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.23 (0.7%) stddev-sans-first:1.01 06:05:25 INFO - PROCESS | 1646 | Values: 186.0 184.0 183.0 184.0 182.0 185.0 182.0 184.0 184.0 184.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:64.70 Median:59.00 stddev:10.69 (18.1%) stddev-sans-first:10.20 06:05:25 INFO - PROCESS | 1646 | Values: 78.0 58.0 59.0 86.0 59.0 57.0 59.0 58.0 75.0 58.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.50 stddev:8.61 (11.0%) stddev-sans-first:7.62 06:05:25 INFO - PROCESS | 1646 | Values: 90.0 73.0 86.0 69.0 70.0 69.0 85.0 69.0 70.0 84.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:1.87 (2.1%) stddev-sans-first:1.99 06:05:25 INFO - PROCESS | 1646 | Values: 87.0 86.0 88.0 85.0 89.0 85.0 89.0 86.0 89.0 84.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:95.00 stddev:17.49 (18.4%) stddev-sans-first:18.40 06:05:25 INFO - PROCESS | 1646 | Values: 95.0 95.0 94.0 94.0 94.0 95.0 106.0 150.0 95.0 95.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.50 stddev:1.48 (1.7%) stddev-sans-first:1.33 06:05:25 INFO - PROCESS | 1646 | Values: 88.0 87.0 85.0 85.0 86.0 85.0 87.0 85.0 87.0 83.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.00 stddev:1.10 (1.9%) stddev-sans-first:1.17 06:05:25 INFO - PROCESS | 1646 | Values: 58.0 58.0 56.0 58.0 58.0 59.0 58.0 60.0 59.0 57.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:76.50 stddev:5.23 (6.8%) stddev-sans-first:4.50 06:05:25 INFO - PROCESS | 1646 | Values: 66.0 76.0 80.0 77.0 74.0 70.0 71.0 72.0 77.0 84.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:87.00 stddev:3.82 (4.4%) stddev-sans-first:4.00 06:05:25 INFO - PROCESS | 1646 | Values: 88.0 85.0 88.0 86.0 90.0 85.0 86.0 91.0 86.0 77.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:139.50 stddev:13.09 (9.4%) stddev-sans-first:13.80 06:05:25 INFO - PROCESS | 1646 | Values: 139.0 137.0 140.0 138.0 137.0 140.0 139.0 138.0 180.0 142.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:1.97 (1.1%) stddev-sans-first:1.96 06:05:25 INFO - PROCESS | 1646 | Values: 170.0 174.0 172.0 172.0 176.0 171.0 170.0 170.0 171.0 173.0 06:05:25 INFO - PROCESS | 1646 | 06:05:25 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.07 (1.6%) stddev-sans-first:1.12 06:05:25 INFO - PROCESS | 1646 | Values: 66.0 68.0 68.0 68.0 67.0 66.0 66.0 65.0 66.0 66.0 06:05:25 INFO - PROCESS | 1646 | -------- Summary: end -------- 06:05:25 INFO - PROCESS | 1646 | 06:05:26 INFO - TEST-INFO | 1646: exit 0 06:05:26 INFO - TEST-OK | kraken | took 59142ms 06:05:26 INFO - SUITE-END | took 1179s 06:05:26 INFO - Completed test suite (00:19:39) 06:05:26 INFO - javascript benchmark 06:05:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7153.07065513563, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3276.4102461556918, 3293.538904553363, 3299.0, 3324.2852786650865, 3351.899443016709, 4900.828471003516, 5447.863803404917, 5499.422560631137, 5604.187392828037, 5656.377798442168, 5535.723213839312, 5604.467575580324, 5629.155626656004, 5649.180868774025, 5675.0, 5040.924386134211, 5043.268726034723, 5102.336696229503, 5126.820561280356, 5145.974270128644, 5184.740762961856, 5199.402068762103, 5200.895982080375, 5215.956808638269, 5234.398044224906, 4931.728754918478, 4960.479149689303, 4964.503549645043, 5000.849974500789, 5028.22062580297, 5049.116404629175, 5102.566281866046, 5132.101882170605, 5169.483051694838, 5188.6367954243215, 5034.748262586892, 5039.0, 5058.4435712071645, 5085.79656813727, 5117.181250999822, 5473.507384335389, 5498.3127109111365, 5599.272094627713, 5605.607607467479, 5617.634853734535, 6084.178635884144, 6120.690942950886, 6126.081087836816, 6129.175413489954, 6157.0, 6192.0, 6246.812595622138, 6249.468795152417, 6260.060990851409, 6262.342454042351, 5727.5131613812455, 5735.4551317624755, 5750.166225897264, 5764.74058667361, 5800.7679692812235, 6078.787242446493, 6144.310314663414, 6174.876502469925, 6232.563720539519, 6276.184096067482, 5797.942020579824, 5835.153902684087, 5855.882882342329, 6060.303065147565, 6120.60216085953, 6064.545159113084, 6078.6048906820915, 6093.96953015241, 6096.847578810565, 6103.05402662588, 5901.3508514063415, 5980.611260268069, 5985.072313791369, 5986.3714309997695, 6001.12983617373, 6025.945459544562, 6030.638161710312, 6034.336223015464, 6038.094285857156, 6039.667818270037, 4823.614110871122, 4824.782884770194, 4837.129316722953, 4839.128956787812, 4847.75761211938, 4337.652987760972, 4382.364557139197, 4397.054633253866, 4406.889827754268, 4413.536578659195, 5642.802501912413, 5660.915086273709, 5691.658500489983, 5692.288463942007, 5701.686407247558, 5422.891542169214, 5863.912041319383, 6105.633661980295, 6218.595791273643, 6233.812985610439, 5640.746166422521, 5649.265595472562, 5659.518940889988, 5672.0, 5708.457696518824, 5352.437994010651, 5360.463953604608, 5361.865953351119, 5370.382406023279, 5373.0, 6139.492138333381, 6302.684865756785, 6304.243490781136, 6312.52656050798, 6319.715612797343, 6088.969555152196, 6103.428585615474, 6108.258823178673, 6109.890546867989, 6110.103144028831, 5255.369355677343, 6235.532335074979, 6238.157848690369, 6238.719257633326, 6245.6252624842655, 6118.174046503665, 6142.713648198372, 6144.126041557368, 6173.919564076358, 6179.134921111138, 6223.315435302023, 6224.813255602339, 6225.190725205694, 6236.594621349779, 6266.68666566679, 5201.349831271091, 5779.7110144493445, 5810.73851676684, 6012.759489620366, 6022.969885150546, 5846.503047240862, 5855.795047173328, 5856.648601083778, 5857.736401861841, 5876.588638795414, 5640.153976903333, 5705.714714264187, 5706.115552089433, 5731.742071606871, 5731.8567035824435, 5856.648601083949, 5857.882842343214, 5857.9121313179485, 5858.121281807763, 5866.178734977021, 5730.942690573208, 5753.942460575508, 5760.107183386582, 5782.7686892523825, 5804.709764511673], "value": 5577.632651484265, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.58450148370923, 490.2278910715624, 508.59058457941353, 511.9923201151986, 527.4909712127799, 38170.23659526808, 40610.78167654971, 40696.59303406969, 42366.57633423665, 42372.78813605928, 40633.39049914254, 41054.58945410545, 41073.58926410735, 41123.0, 41123.971900702425, 7932.881006784903, 8173.141820108863, 9158.175764181222, 9397.295202859812, 9418.387804792666, 7996.240357166089, 8099.352051835868, 8135.959320203391, 10873.238873278873, 11082.889171108267, 11066.557337706483, 11101.167412444058, 11115.333080015185, 11142.331460112378, 11172.0, 11116.499757510892, 11221.102311815035, 11224.326540407601, 11233.0, 11246.268992515461, 7921.683132674686, 8097.311728503084, 8133.877991830127, 8172.387070969701, 10876.347419154876, 29559.704402956126, 38264.0, 38944.805275973726, 38955.61044389548, 40192.0, 3868.9613103868883, 3887.300285948528, 3887.941680874789, 3920.8431662733456, 4019.3970904364287, 29305.120846374644, 29670.324177060204, 29763.107106786832, 29803.70196298031, 29852.701472985427, 3017.230606195406, 3231.1922019495128, 3291.8024918504966, 3292.7859689120132, 3300.455424854896, 445923.0, 451079.0, 451256.0, 452754.9999999967, 452938.0, 49235.0, 50993.74503127535, 51064.74467627638, 58093.0, 58453.0, 71036.64481677559, 72117.63941180366, 72280.0, 72286.63856680683, 76211.61894190493, 12082.275063496043, 15364.692706145814, 15579.454719084777, 15792.21038948071, 15903.284352203947, 11947.880521194851, 12050.51797928073, 12141.69645758863, 12148.271103733805, 12166.513339466499, 19724.309649162496, 19792.90103549502, 19845.106970186378, 19870.801291987184, 19889.60220795576, 7505.348823258874, 8390.916090839135, 8406.327493800482, 8411.789705257417, 8422.30486244396, 858.8754630578657, 869.8042940338373, 872.1292826886294, 880.216607219575, 899.3389858454032, 29144.854275728485, 29153.85423072871, 29364.412711745645, 29904.102876913727, 30102.0, 1056.4328681304503, 1083.0631503749357, 1083.8049151152711, 1094.5020015892749, 1178.4545381974892, 14686.779698304534, 15096.471623493344, 15192.544223673307, 15363.46227882041, 15401.691966160839, 609525.0, 612802.9359853172, 613049.0, 613118.0, 613238.0, 57415.42584574184, 57752.422475775544, 57860.4213957855, 57957.420425796045, 57957.710211449514, 1376.8554301798367, 1395.3162950154351, 1439.251589173603, 1441.2001339256603, 1445.6674964758165, 27761.722382775915, 27869.0, 28352.60534617138, 29072.273193169494, 29309.7069029307, 49404.0, 49780.0, 49837.252441214136, 49881.75059124681, 49976.500234997184, 425574.0, 428034.0, 428943.0, 429155.0, 429556.0, 39748.60251397449, 40309.59690403176, 41031.20407122637, 41205.381919270636, 41251.0, 41246.175076498905, 45590.31614525851, 45829.08341833078, 45921.31118033299, 46231.0, 37150.44274335941, 37238.0, 37391.62608373881, 38186.427203592524, 38202.42696359501, 33620.99137025893, 35117.47323790196, 36830.44754328741, 37832.810835945646, 37983.0, 33851.66148338583, 33977.0, 34593.0, 34634.82682586571, 34686.479702803976, 23228.41928951722, 28960.0, 29810.0, 31067.0, 31222.0, 23297.068117275117, 23310.417239569146, 23604.0, 23654.763452365256, 23733.0], "value": 19715.9185517757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.8132443116226, 394.8025987006497, 395.74672209785723, 406.6380920980327, 424.21520187652845, 14099.929500352497, 15006.099634021954, 18038.4588462346, 20859.0, 21288.254911078115, 20890.955452227405, 21164.8941755291, 21194.788052119475, 21439.57120857582, 21494.8505149485, 5849.912251316234, 5872.471477567018, 6017.368176341487, 6063.939360606381, 6153.784617538365, 3841.5966323536045, 5907.645541267516, 6014.548908831834, 6017.338092809788, 6021.0, 4006.8597599084037, 6285.874282514347, 6555.475561955032, 6584.9012264816065, 6677.332266773308, 6420.101185833987, 6596.901046484307, 6649.5677780944325, 6676.09872667191, 6679.499037572202, 5836.358000619928, 5840.737166827503, 5841.591088623798, 5848.0, 5848.707564621752, 24327.635085473732, 25368.0, 25378.82764654418, 25398.492030159294, 25658.87170564154, 2437.463757973243, 2691.3809823740453, 2707.187843646918, 2738.438620082878, 2743.3004583831193, 15356.923215383964, 15369.077855328716, 15390.615234619112, 15689.52931412048, 16338.83661163397, 1784.839364457192, 1818.6453641539906, 1831.9749402455595, 1839.9724004139948, 1858.962820743591, 125201.0, 126825.0, 127206.0, 128997.42005159844, 129426.0, 11450.854914508649, 11467.770644587228, 11605.6518304451, 11783.0, 11808.933436648553, 14802.481913132988, 14816.666250093858, 14858.182799945891, 14862.777058344134, 14897.553073407815, 8244.670213191404, 8506.617202225914, 8514.361422893307, 8614.741557753277, 8692.913385826772, 8040.0, 8288.295494882881, 8327.250547450758, 8446.915530844613, 8521.44610600309, 7074.434045276365, 7147.642617869085, 7202.855942881113, 7203.603801790847, 7212.3869471094495, 1499.5201535508534, 1916.3101283537915, 1943.1061711612533, 1949.1813438355925, 1952.6094781043848, 391.2156126965416, 435.7211384713786, 1010.777628921636, 1043.436544266103, 1059.426751274357, 11268.0, 11868.406579670982, 12235.959943404732, 12240.938795305967, 12319.69200769988, 447.65754198038525, 449.3147949377226, 455.2988397867313, 460.61768731953066, 474.6795912758943, 6016.879662406727, 10634.20243481742, 11755.177137600285, 12089.335086570323, 12340.382980850922, 599719.0, 662705.0, 662727.0, 670130.0, 679542.3189970545, 88661.11338886658, 148688.0, 149162.25418872837, 152394.28451439424, 155575.3318501117, 1089.8746644135867, 1094.534822700365, 1135.517405102828, 1136.3182090745486, 1140.8516892803882, 19467.805321947166, 19616.0, 19847.503812404804, 19875.701864472332, 19892.90053549723, 50664.98670026653, 51449.19927802509, 52526.42420727384, 57322.0, 58059.838803222854, 71041.57916841737, 78908.60545697235, 83357.0, 85972.0, 87698.0, 7110.288971103055, 7255.09311336083, 7378.188399276182, 7393.741219057307, 7530.397568194641, 9749.415035097916, 11922.821157682814, 12218.572349967708, 12441.0, 13281.535146269835, 10938.12495000414, 11030.359981206022, 11041.0, 11449.94275028653, 11574.305541667527, 9400.81198376015, 10454.581816727246, 11030.724231894266, 11034.668959931216, 11366.033887119356, 11497.962881371821, 11738.826117388193, 11782.528698852293, 11819.172657913863, 11820.940895295469, 6967.1291088613925, 7210.783676489714, 7381.224971377928, 7491.213422590549, 7572.864070389265, 9969.152622027208, 11063.336199828036, 11089.833652495057, 11327.773444530898, 11423.457833192746], "value": 9543.519303151388, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.879735222506, 1433.655922578581, 1437.813084299041, 1441.675622984828, 1442.704245629646, 8077.717279895198, 8518.403711740166, 8539.274161696256, 8539.658413663461, 8546.40175187737, 8117.350611951045, 8240.95879520603, 8313.750587482386, 8326.625301861415, 8491.490510569354, 2143.2820005298167, 2148.1407437025196, 2165.220520612578, 2190.9624049633835, 2193.4297082758517, 2089.874607523546, 2180.2587120379067, 2183.1049269799387, 2185.311626837549, 2189.7262842144733, 1872.7939926608062, 1935.874168179071, 1946.211784227383, 2114.8942552872372, 2186.420598541388, 2175.9456013599706, 2195.3194509701884, 2196.198387588535, 2200.163937703662, 2201.7908298711677, 1892.5174080609356, 2144.1744928202756, 2170.5550362175873, 2171.6742488626833, 2180.073468775764, 7570.962145189294, 7712.57580833054, 7714.267144621252, 7719.0, 7724.948645285081, 4891.951080489185, 5029.119904016819, 5041.521055499722, 5094.235864620299, 5094.388673359221, 3311.768176227665, 3383.8477268522975, 3409.09658940381, 3458.6195518492946, 3468.236987862666, 1204.2353105777806, 1213.6359092272282, 1228.3735294999526, 1247.819066235391, 1262.0219330019218, 10647.520861561248, 10815.405152716676, 10821.134309255242, 10849.294795838245, 10855.24013319068, 4103.425520427142, 4353.455818022747, 4424.955750442455, 4433.401490798766, 4450.6884518083425, 8698.608562614698, 8703.042665306812, 8708.869366959501, 8716.825663486821, 8733.432326898732, 5051.166557518042, 5056.9494305057215, 5057.6459647824295, 5058.620603454756, 5064.5441910228255, 4549.863504094882, 4687.367205427292, 4725.706385293787, 4727.290906364073, 4777.4983626718695, 3431.7083047940705, 3444.3800115979366, 3465.7054150396993, 3519.964800352015, 3520.436730123168, 958.5734348215117, 1089.0451639307462, 1094.6114129484079, 1097.983530247047, 1103.768208676187, 763.2672634271162, 810.4772421787914, 895.4492986813193, 908.6728777640044, 946.4321407155652, 5183.870403239949, 5670.688112153789, 5769.048107062372, 5781.335146458127, 6056.454919057218, 950.9952450237843, 958.7746879483316, 985.6500942165571, 987.4618333008527, 1028.5063169678447, 3680.4479328100997, 3690.870819521304, 3767.095896984704, 3816.9809150954625, 3851.9614803852164, 9051.773705657411, 9418.623255069857, 9507.477088760046, 9706.0, 9903.554340054714, 9979.700608981742, 9981.301308908307, 10014.24893133018, 10014.49927503622, 10018.29871908974, 6958.060661810692, 7268.781936541912, 7486.363659089038, 7642.4650274480255, 7746.651400687096, 5026.698398096126, 5219.373675158853, 5468.152436372368, 5629.296337957755, 5785.82642520725, 9021.32340074484, 9108.271338293052, 9209.0, 9243.352965292364, 9252.398594091363, 9268.768280793034, 9269.860952085859, 9309.487978161265, 9378.0, 9412.529373531159, 6990.300969902969, 7168.462365322517, 7176.533525320838, 7178.641067946477, 7194.96402518005, 7701.576413297323, 7719.382449404146, 7734.92265077342, 7780.688772449265, 7810.062792464941, 7003.124609423822, 7037.542559733601, 7056.259092795388, 7122.85754284901, 7135.6432178391915, 6602.801915942529, 6629.403353698191, 6651.06885036105, 6671.266160722414, 6684.933150668431, 6331.9050214247745, 6429.7428102875365, 6434.453071488976, 6441.066045423481, 6444.130042444398, 4854.296127061627, 4908.190148625436, 4982.526659967298, 5002.899942001212, 5169.793208271627, 4546.590806827401, 4930.926036109389, 5043.520865517917, 5081.212412076134, 5127.435982042047], "value": 4299.164814366628, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.7544030577831, 359.2312451354103, 366.74144727966785, 371.12784955354937, 372.61248301766165, 4480.843170489034, 4498.820047198116, 5227.712475813829, 5258.710770907599, 5347.705876176804, 5024.743814046488, 5219.608529360304, 5269.525742683158, 5275.6043296752805, 5299.60252981027, 3551.7691350062164, 3882.9611703883024, 4027.395890616416, 4042.4744783178144, 4059.9594004060023, 4053.756774593534, 4057.4116753070934, 4067.0717083699687, 4071.491063617048, 4088.9591104089022, 3858.9002134391703, 3934.350832112698, 3946.7237293389476, 4017.698672599552, 4032.5765794591584, 3939.034936440576, 3940.5862384449792, 3944.9013774655577, 3964.405339199114, 3977.3238549446664, 3055.572219889217, 3451.7583769135917, 3452.94685121038, 3477.5479187705437, 3485.5468789057513, 4308.741475511448, 4711.057788442326, 5143.376877792315, 5199.53204211619, 5225.869353266161, 3675.7059435245114, 3852.6339997700175, 4294.892627684301, 4583.404157459542, 4632.606228470584, 4851.175300198975, 5486.780528778844, 5531.806386776484, 5546.947629377116, 5574.554035677136, 3142.6385965614018, 3264.9183770405693, 3567.5940213835893, 3587.856485740567, 3838.5201849768778, 5104.412992505872, 5471.7565798023015, 5731.570132240098, 5736.959411511905, 5811.535077193815, 3317.102404674498, 3464.930701385958, 3866.96133038666, 3878.8836334909997, 3916.667083297895, 3983.342748446532, 3990.2219067281603, 3991.9201615967518, 4000.759954402687, 4007.6593489553134, 3870.109874728772, 4334.306510958231, 4338.869833904988, 4460.598546130864, 4682.976585117052, 4995.600351971906, 5627.915581266284, 5641.407652196543, 5645.0, 5650.378458369576, 1079.3523885668535, 1258.4714419943646, 1260.8148340559844, 1270.4156088199347, 1276.3618190904547, 795.8778122846874, 804.7384600005022, 871.9215270625674, 872.1104473437059, 874.881890944727, 4125.278076336629, 4212.431321771583, 4918.573613652073, 4953.356063711766, 5014.0695832104175, 4924.75376231187, 5252.05463016653, 5478.260434841245, 5497.835064948058, 5513.421090785409, 4064.573219811877, 4478.57453541913, 4562.634989200856, 4806.351142595775, 4970.136198925403, 2394.772496612819, 2475.4553998120386, 2557.3350928758655, 2809.634747482814, 2842.928926776806, 4891.755412229374, 5135.640505164677, 5379.596530260246, 5592.692401917852, 5620.297462817148, 4681.401821988761, 4824.697331720416, 5171.689698618095, 5471.398146203915, 5540.282512421082, 4480.887977800516, 5102.984321193182, 5202.88138050321, 5401.081816091274, 5613.354464236665, 5673.404292549388, 5683.82239054303, 5687.459691329483, 5705.600607957404, 5777.653340799566, 4842.806287748451, 5292.841214763564, 5600.963821693, 5622.32532096151, 5668.744906479136, 5473.648449482591, 5912.70436478183, 5937.435943585352, 5950.375210602998, 5970.671613061149, 3300.372929143455, 3823.5602905666206, 3872.7095467839463, 3879.86420475282, 3898.317794386028, 5313.415524292402, 5697.572682048946, 5720.885582288248, 5741.45456181662, 5747.281589801275, 4518.322251662145, 5586.078297081099, 5601.2076135637835, 5613.831585052455, 5617.32592088952, 5184.507471790174, 5567.69377684215, 5571.582131340247, 5573.526250268773, 5573.637713548606, 4296.9902073013445, 4746.715197088186, 5061.0, 5414.539764119936, 5494.340679118532, 5274.314339135888, 5487.807926722546, 5502.80740174092, 5507.310102231434, 5513.917291240555, 4781.402324709411, 5269.868253293698, 5401.162819762943, 5459.808906688247, 5501.679596896796], "value": 3989.1396401557076, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.6519388096775, 753.5290443472826, 765.5980610179655, 772.7313464047081, 850.9702160424384, 14498.710025799492, 14597.416103355867, 14866.0, 14954.476593319236, 15034.398624055038, 13554.93222533886, 13867.653308667288, 13873.306334683275, 13993.650158746037, 14012.369443375048, 876.7501262140275, 889.4129874282975, 894.0344428017758, 894.472261365794, 894.5661354243219, 858.0947100808644, 884.7213127864734, 886.2201262888649, 893.5576889439736, 894.1773568317132, 897.7710683775628, 901.7339884733987, 936.4708939449189, 939.5208443693731, 941.1576638908207, 934.9065093490631, 935.8596210568435, 936.1106948399048, 936.4053825820653, 938.7934654376027, 697.8953157026436, 698.6471831724997, 698.671624336561, 699.5732603112098, 699.7690762048512, 14705.117692938458, 14874.181919994397, 14908.105513669214, 14922.62693432662, 15156.090634561851, 13785.103968242032, 14225.573232803032, 14226.28868556568, 14227.35976881043, 14459.56621301352, 11732.706682332924, 11753.588624398106, 11799.056075513938, 11825.11311651621, 11925.942538187419, 3343.882964096245, 3486.459598762196, 3645.2162785001087, 3786.9964459418275, 3799.8670046548236, 55194.0, 56982.0, 77038.6148069256, 95389.52305238499, 103686.48156759243, 10613.204009699304, 10703.143748500257, 10810.324669484753, 11055.170862185369, 11173.385463799568, 18311.450656480327, 18945.0, 21964.780352196594, 22311.0, 22329.776702233095, 7550.3582195514, 7619.199984001711, 7765.53406795594, 7769.922300777033, 7882.290593846581, 4069.206504731608, 4153.273177193979, 4154.563770804022, 4157.189348077157, 4167.74993500391, 3466.3587236361354, 3520.2431477232517, 3550.218951830593, 3550.8047057411572, 3618.746687731886, 718.4755128755933, 720.5388551327155, 721.4949535325293, 736.4182295986218, 736.7973807202955, 674.5581644023173, 679.8742232686968, 698.472653146881, 713.3045280851129, 719.6869361827622, 9865.704028879145, 9908.455034973002, 10300.639477618248, 10414.895851041545, 10494.47527623631, 672.1262358933465, 673.8483841135705, 680.2415306932735, 694.5173104692292, 695.8886578147473, 4643.419572553431, 4965.935199629197, 4967.3294105296045, 4970.707616019861, 4990.683461280494, 85116.0, 91931.0806891936, 99902.50048749709, 102893.94212115716, 103215.45176822353, 74839.0, 84904.72642910361, 85697.14302857016, 86086.56956715176, 87869.68195477073, 59783.0, 71405.64297178584, 73775.0, 75248.0, 77080.2291977073, 9258.0, 9603.461282892895, 9668.0, 9698.563564639468, 9701.805963880543, 45488.0, 46474.76762616165, 48044.03911921672, 49084.01831963269, 51031.97936041332, 61814.0, 62708.059379110266, 63026.68486657537, 63058.48710424034, 63701.4074648119, 12790.168639038433, 12905.483780648668, 12960.740785184054, 13441.059125861097, 13449.991250656436, 14471.565853024425, 14529.055611385225, 14577.85422145765, 14682.486112985995, 15433.459828905934, 13491.392887320291, 13503.527376541773, 13635.522756703467, 14032.579022628928, 14243.145411275356, 13602.319884005958, 13612.319384030561, 13622.659433514242, 13777.724445511234, 14212.005159638726, 12901.806472902726, 13874.861251387762, 13930.791038134234, 13937.0, 14046.227457489937, 10791.784164316827, 11187.832182517432, 11224.719382015515, 11645.359505226956, 11680.47437865315, 10199.0, 10681.27149474189, 10810.729731756453, 10824.296420732628, 10902.836457452986], "value": 7441.61042108316, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1430.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 113.0, 113.0, 111.0, 113.0, 111.0, 113.0, 112.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 110.0, 145.0, 110.0, 110.0, 109.0, 110.0, 132.0, 109.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 183.0, 184.0, 182.0, 185.0, 182.0, 184.0, 184.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 59.0, 86.0, 59.0, 57.0, 59.0, 58.0, 75.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 86.0, 69.0, 70.0, 69.0, 85.0, 69.0, 70.0, 84.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 89.0, 85.0, 89.0, 86.0, 89.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 106.0, 150.0, 95.0, 95.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 85.0, 85.0, 86.0, 85.0, 87.0, 85.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 56.0, 58.0, 58.0, 59.0, 58.0, 60.0, 59.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 80.0, 77.0, 74.0, 70.0, 71.0, 72.0, 77.0, 84.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 90.0, 85.0, 86.0, 91.0, 86.0, 77.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 140.0, 138.0, 137.0, 140.0, 139.0, 138.0, 180.0, 142.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 172.0, 172.0, 176.0, 171.0, 170.0, 170.0, 171.0, 173.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 68.0, 67.0, 66.0, 66.0, 65.0, 66.0, 66.0], "value": 66.6, "unit": "score"}]}]} 06:05:26 INFO - Return code: 0 06:05:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:05:26 ERROR - # TBPL SUCCESS # 06:05:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:05:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:05:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:05:26 INFO - 2017-02-03 06:05:26.766 screenresolution[1650:10474] starting screenresolution argv=screenresolution get 06:05:26 INFO - 2017-02-03 06:05:26.769 screenresolution[1650:10474] Display 0: 1600x1200x32@60 06:05:26 INFO - 2017-02-03 06:05:26.775 screenresolution[1651:10477] starting screenresolution argv=screenresolution list 06:05:26 INFO - Available Modes on Display 0 06:05:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:05:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:05:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:05:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:05:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:05:27 INFO - Intel Iris: 06:05:27 INFO - Chipset Model: Intel Iris 06:05:27 INFO - Type: GPU 06:05:27 INFO - Bus: Built-In 06:05:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:05:27 INFO - Vendor: Intel (0x8086) 06:05:27 INFO - Device ID: 0x0a2e 06:05:27 INFO - Revision ID: 0x0009 06:05:27 INFO - Displays: 06:05:27 INFO - Display: 06:05:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:05:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:05:27 INFO - Display Serial Number: 1600x1200 60 06:05:27 INFO - Main Display: Yes 06:05:27 INFO - Mirror: Off 06:05:27 INFO - Online: Yes 06:05:27 INFO - Rotation: Supported 06:05:27 INFO - Return code: 0 06:05:27 INFO - Running post-action listener: _resource_record_post_action 06:05:27 INFO - [mozharness: 2017-02-03 14:05:27.014318Z] Finished run-tests step (success) 06:05:27 INFO - Running post-run listener: _resource_record_post_run 06:05:27 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32480256; Write bytes: 554642432; Read time: 197; Write time: 1677 06:05:27 INFO - TinderboxPrint: CPU usage
53.9% 06:05:27 INFO - TinderboxPrint: I/O read bytes / time
32,480,256 / 197 06:05:27 INFO - TinderboxPrint: I/O write bytes / time
554,642,432 / 1,677 06:05:27 INFO - TinderboxPrint: CPU idle
2,218.1 (46.1%) 06:05:27 INFO - TinderboxPrint: CPU system
962.9 (20.0%) 06:05:27 INFO - TinderboxPrint: CPU user
1,628.6 (33.9%) 06:05:27 INFO - TinderboxPrint: Swap in / out
280,428,544 / 0 06:05:27 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244072960; Write bytes: 238159360; Read time: 17765; Write time: 514 06:05:27 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30452736; Write bytes: 299038208; Read time: 180; Write time: 1120 06:05:27 INFO - Running post-run listener: _upload_blobber_files 06:05:27 INFO - Blob upload gear active. 06:05:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:05:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:05:27 INFO - (blobuploader) - INFO - Open directory for files ... 06:05:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:05:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:29 INFO - (blobuploader) - INFO - Done attempting. 06:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:29 INFO - (blobuploader) - INFO - Done attempting. 06:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:05:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:31 INFO - (blobuploader) - INFO - Done attempting. 06:05:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:05:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:05:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:34 INFO - (blobuploader) - INFO - Done attempting. 06:05:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:05:34 INFO - Return code: 0 06:05:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:05:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:05:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc5514c0e1573ca523fa75df422df2a7eb67c72f2df6cf8b6f1225365a4d9721bbb90a69e4df6e6761f1ac316b2d1d2372a044057273725cfeb9995f37cb066d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc68dc13c7331beb9f51c3a73191402c8be1d72092d7bc02446020acb87f86d0400ca215d8a34b60e3963cbe1063704897860080adac6b3d18e3f1cc76293d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2742c285a3c136dcdbe1ede8766d75867549ed8b14ca531dccba0aa1e208ff9a72709d7a1324066090318a3ffc92c22549aae1ab274c2ac2ecea7aea61f19470", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a5d62df8ff344e7821fc877e2546dbfd50f281c8f6d97ce11a6578ec8c9cc9632f80090e53191c76c1bcbd94973dc778aad8187e59d8eecd1e46d91133a736"} 06:05:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:05:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:05:34 INFO - Contents: 06:05:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc5514c0e1573ca523fa75df422df2a7eb67c72f2df6cf8b6f1225365a4d9721bbb90a69e4df6e6761f1ac316b2d1d2372a044057273725cfeb9995f37cb066d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc68dc13c7331beb9f51c3a73191402c8be1d72092d7bc02446020acb87f86d0400ca215d8a34b60e3963cbe1063704897860080adac6b3d18e3f1cc76293d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2742c285a3c136dcdbe1ede8766d75867549ed8b14ca531dccba0aa1e208ff9a72709d7a1324066090318a3ffc92c22549aae1ab274c2ac2ecea7aea61f19470", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a5d62df8ff344e7821fc877e2546dbfd50f281c8f6d97ce11a6578ec8c9cc9632f80090e53191c76c1bcbd94973dc778aad8187e59d8eecd1e46d91133a736"} 06:05:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:05:34 INFO - Copying logs to upload dir... 06:05:34 INFO - mkdir: /builds/slave/test/build/upload/logs 06:05:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.187754 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-03 06:05:34.230477) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 06:05:34.233820) ========= 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.j5eZlMUQJQ/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.vreamwBMJa/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/cc5514c0e1573ca523fa75df422df2a7eb67c72f2df6cf8b6f1225365a4d9721bbb90a69e4df6e6761f1ac316b2d1d2372a044057273725cfeb9995f37cb066d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc68dc13c7331beb9f51c3a73191402c8be1d72092d7bc02446020acb87f86d0400ca215d8a34b60e3963cbe1063704897860080adac6b3d18e3f1cc76293d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2742c285a3c136dcdbe1ede8766d75867549ed8b14ca531dccba0aa1e208ff9a72709d7a1324066090318a3ffc92c22549aae1ab274c2ac2ecea7aea61f19470", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a5d62df8ff344e7821fc877e2546dbfd50f281c8f6d97ce11a6578ec8c9cc9632f80090e53191c76c1bcbd94973dc778aad8187e59d8eecd1e46d91133a736"} build_url:https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/I5NqwlSBRqGtjJL5F4eG0A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc5514c0e1573ca523fa75df422df2a7eb67c72f2df6cf8b6f1225365a4d9721bbb90a69e4df6e6761f1ac316b2d1d2372a044057273725cfeb9995f37cb066d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc68dc13c7331beb9f51c3a73191402c8be1d72092d7bc02446020acb87f86d0400ca215d8a34b60e3963cbe1063704897860080adac6b3d18e3f1cc76293d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2742c285a3c136dcdbe1ede8766d75867549ed8b14ca531dccba0aa1e208ff9a72709d7a1324066090318a3ffc92c22549aae1ab274c2ac2ecea7aea61f19470", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62a5d62df8ff344e7821fc877e2546dbfd50f281c8f6d97ce11a6578ec8c9cc9632f80090e53191c76c1bcbd94973dc778aad8187e59d8eecd1e46d91133a736"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-03 06:05:34.268909) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 06:05:34.269250) ========= 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.j5eZlMUQJQ/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.vreamwBMJa/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.005145 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-03 06:05:34.318366) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 06:05:34.318678) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-03 06:05:34.319003) ========= ========= Total master_lag: 0.18 =========