builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1483810551.78 results: success (0) buildid: 20170107082358 builduid: 400bda61de2745a3a3fd0f088d76aabd revision: e9d16569a7b4518e3a90a0512128b838118dea55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.782332) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.782750) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.783035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.808617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.808912) ========= 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.sQHKQtTboK/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.it3FrhUaZH/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-07 09:35:51-- 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% 46.6M=0s 2017-01-07 09:35:51 (46.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119267 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.952342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:51.952661) ========= 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.sQHKQtTboK/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.it3FrhUaZH/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.019244 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:52.003775) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 09:35:52.004150) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e9d16569a7b4518e3a90a0512128b838118dea55 --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 e9d16569a7b4518e3a90a0512128b838118dea55 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sQHKQtTboK/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.it3FrhUaZH/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-07 09:35:52,082 truncating revision to first 12 chars 2017-01-07 09:35:52,082 Setting DEBUG logging. 2017-01-07 09:35:52,082 attempt 1/10 2017-01-07 09:35:52,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e9d16569a7b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 09:35:53,304 unpacking tar archive at: mozilla-central-e9d16569a7b4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.490729 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 09:35:53.525231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:53.525559) ========= script_repo_revision: e9d16569a7b4518e3a90a0512128b838118dea55 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:53.525944) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:53.526207) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 09:35:53.546786) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-07 09:35:53.547069) ========= /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.sQHKQtTboK/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.it3FrhUaZH/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:35:53 INFO - MultiFileLogger online at 20170107 09:35:53 in /builds/slave/test 09:35:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:35:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:35:53 INFO - {'append_to_log': False, 09:35:53 INFO - 'base_work_dir': '/builds/slave/test', 09:35:53 INFO - 'blob_upload_branch': 'Firefox', 09:35:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:35:53 INFO - 'branch': 'Firefox', 09:35:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:35:53 INFO - 'config_files': ('talos/mac_config.py',), 09:35:53 INFO - 'default_actions': ('clobber', 09:35:53 INFO - 'read-buildbot-config', 09:35:53 INFO - 'download-and-extract', 09:35:53 INFO - 'populate-webroot', 09:35:53 INFO - 'create-virtualenv', 09:35:53 INFO - 'install', 09:35:53 INFO - 'run-tests'), 09:35:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:35:53 INFO - 'download_minidump_stackwalk': True, 09:35:53 INFO - 'download_symbols': 'ondemand', 09:35:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:35:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:35:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:35:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:35:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:35:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:35:53 INFO - 'installer_path': 'installer.exe', 09:35:53 INFO - 'log_level': 'info', 09:35:53 INFO - 'log_name': 'talos', 09:35:53 INFO - 'log_to_console': True, 09:35:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:35:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:35:53 INFO - 'opt_config_files': (), 09:35:53 INFO - 'pip_index': False, 09:35:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:53 INFO - 'cmd': ('bash', 09:35:53 INFO - '-c', 09:35:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:53 INFO - 'enabled': True, 09:35:53 INFO - 'halt_on_failure': False, 09:35:53 INFO - 'name': 'check_screen_resolution'},), 09:35:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:35:53 INFO - 'cmd': ('bash', 09:35:53 INFO - '-c', 09:35:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:35:53 INFO - 'enabled': True, 09:35:53 INFO - 'halt_on_failure': False, 09:35:53 INFO - 'name': 'check_screen_resolution'},), 09:35:53 INFO - 'run_cmd_checks_enabled': True, 09:35:53 INFO - 'sps_profile': False, 09:35:53 INFO - 'sps_profile_interval': 0, 09:35:53 INFO - 'suite': 'dromaeojs', 09:35:53 INFO - 'system_bits': '32', 09:35:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:35:53 INFO - 'title': 't-yosemite-r7-0132', 09:35:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:35:53 INFO - 'use_talos_json': True, 09:35:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:35:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:35:53 INFO - 'work_dir': 'build'} 09:35:53 INFO - [mozharness: 2017-01-07 17:35:53.750241Z] Running clobber step. 09:35:53 INFO - Running pre-action listener: _resource_record_pre_action 09:35:53 INFO - Running main action method: clobber 09:35:53 INFO - rmtree: /builds/slave/test/build 09:35:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:35:54 INFO - Running post-action listener: _resource_record_post_action 09:35:54 INFO - [mozharness: 2017-01-07 17:35:54.650507Z] Finished clobber step (success) 09:35:54 INFO - [mozharness: 2017-01-07 17:35:54.650682Z] Running read-buildbot-config step. 09:35:54 INFO - Running pre-action listener: _resource_record_pre_action 09:35:54 INFO - Running main action method: read_buildbot_config 09:35:54 INFO - Using buildbot properties: 09:35:54 INFO - { 09:35:54 INFO - "project": "", 09:35:54 INFO - "product": "firefox", 09:35:54 INFO - "script_repo_revision": "production", 09:35:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:35:54 INFO - "repository": "", 09:35:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:35:54 INFO - "buildid": "20170107082358", 09:35:54 INFO - "pgo_build": "False", 09:35:54 INFO - "basedir": "/builds/slave/test", 09:35:54 INFO - "buildnumber": 50, 09:35:54 INFO - "slavename": "t-yosemite-r7-0132", 09:35:54 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:35:54 INFO - "platform": "yosemite_r7", 09:35:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:35:54 INFO - "branch": "mozilla-central", 09:35:54 INFO - "repo_path": "mozilla-central", 09:35:54 INFO - "revision": "e9d16569a7b4518e3a90a0512128b838118dea55", 09:35:54 INFO - "stage_platform": "macosx64", 09:35:54 INFO - "builduid": "400bda61de2745a3a3fd0f088d76aabd", 09:35:54 INFO - "slavebuilddir": "test" 09:35:54 INFO - } 09:35:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:35:54 INFO - Running post-action listener: _resource_record_post_action 09:35:54 INFO - [mozharness: 2017-01-07 17:35:54.654156Z] Finished read-buildbot-config step (success) 09:35:54 INFO - [mozharness: 2017-01-07 17:35:54.654296Z] Running download-and-extract step. 09:35:54 INFO - Running pre-action listener: _resource_record_pre_action 09:35:54 INFO - Running main action method: download_and_extract 09:35:54 INFO - mkdir: /builds/slave/test/build/tests 09:35:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:54 INFO - trying https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:35:54 INFO - Downloading https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:35:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:35:55 INFO - Downloaded 1199 bytes. 09:35:55 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:35:55 INFO - Using the following test package requirements: 09:35:55 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:35:55 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:35:55 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:35:55 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'jsshell-mac.zip'], 09:35:55 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:35:55 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:35:55 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:35:55 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:35:55 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:35:55 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:35:55 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:35:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:35:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:35:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:35:55 INFO - Fetch https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:35:56 INFO - Expected file size: 16304863 09:35:56 INFO - Obtained file size: 16304863 09:36:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:36:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:36:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:36:03 INFO - Fetch https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:36:04 INFO - Expected file size: 16304863 09:36:04 INFO - Obtained file size: 16304863 09:36:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:36:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:36:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:36:11 INFO - Expected file size: 14403537 09:36:11 INFO - Obtained file size: 14403537 09:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:12 INFO - trying https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:36:12 INFO - Downloading https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:36:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:36:14 INFO - Downloaded 69726295 bytes. 09:36:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:36:14 INFO - mkdir: /builds/slave/test/properties 09:36:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:14 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:14 INFO - Contents: 09:36:14 INFO - build_url:https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:36:14 INFO - Running post-action listener: _resource_record_post_action 09:36:14 INFO - Running post-action listener: set_extra_try_arguments 09:36:14 INFO - [mozharness: 2017-01-07 17:36:14.753381Z] Finished download-and-extract step (success) 09:36:14 INFO - [mozharness: 2017-01-07 17:36:14.753519Z] Running populate-webroot step. 09:36:14 INFO - Running pre-action listener: _resource_record_pre_action 09:36:14 INFO - Running main action method: populate_webroot 09:36:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:14 INFO - '--tppagecycles', 09:36:14 INFO - '7'], 09:36:14 INFO - 'tests': ['tsvgm']}}, 09:36:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['damp', 'tps']}, 09:36:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'tests': ['damp', 'tps']}, 09:36:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['dromaeo_dom']}, 09:36:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['basic_compositor_video']}, 09:36:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:36:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['a11yr', 09:36:14 INFO - 'ts_paint', 09:36:14 INFO - 'tpaint', 09:36:14 INFO - 'sessionrestore', 09:36:14 INFO - 'sessionrestore_no_auto_restore', 09:36:14 INFO - 'tabpaint']}, 09:36:14 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:14 INFO - 'ts_paint', 09:36:14 INFO - 'tpaint', 09:36:14 INFO - 'sessionrestore', 09:36:14 INFO - 'sessionrestore_no_auto_restore', 09:36:14 INFO - 'tabpaint']}, 09:36:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:14 INFO - 'ts_paint', 09:36:14 INFO - 'tpaint', 09:36:14 INFO - 'sessionrestore', 09:36:14 INFO - 'sessionrestore_no_auto_restore', 09:36:14 INFO - 'tabpaint']}, 09:36:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:14 INFO - 'ts_paint', 09:36:14 INFO - 'tpaint', 09:36:14 INFO - 'sessionrestore', 09:36:14 INFO - 'sessionrestore_no_auto_restore', 09:36:14 INFO - 'tabpaint']}, 09:36:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['a11yr', 09:36:14 INFO - 'ts_paint', 09:36:14 INFO - 'tpaint', 09:36:14 INFO - 'sessionrestore', 09:36:14 INFO - 'sessionrestore_no_auto_restore', 09:36:14 INFO - 'tabpaint']}, 09:36:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['a11yr', 09:36:14 INFO - 'ts_paint', 09:36:14 INFO - 'tpaint', 09:36:14 INFO - 'sessionrestore', 09:36:14 INFO - 'sessionrestore_no_auto_restore', 09:36:14 INFO - 'tabpaint']}, 09:36:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['tsvgx', 09:36:14 INFO - 'tsvgr_opacity', 09:36:14 INFO - 'tart', 09:36:14 INFO - 'tscrollx', 09:36:14 INFO - 'cart', 09:36:14 INFO - 'tsvg_static']}, 09:36:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:14 INFO - 'tsvgr_opacity', 09:36:14 INFO - 'tart', 09:36:14 INFO - 'tscrollx', 09:36:14 INFO - 'cart', 09:36:14 INFO - 'tsvg_static']}, 09:36:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'talos_options': ['--disable-e10s'], 09:36:14 INFO - 'tests': ['tp5o']}, 09:36:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:36:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'talos_options': ['--disable-e10s', 09:36:14 INFO - '--xperf_path', 09:36:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:14 INFO - 'tests': ['tp5n']}, 09:36:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:36:14 INFO - 'talos_options': ['--xperf_path', 09:36:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:14 INFO - 'tests': ['tp5n']}}, 09:36:14 INFO - 'talos.zip': {'path': '', 09:36:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:14 INFO - Running post-action listener: _resource_record_post_action 09:36:14 INFO - [mozharness: 2017-01-07 17:36:14.772146Z] Finished populate-webroot step (success) 09:36:14 INFO - [mozharness: 2017-01-07 17:36:14.772281Z] Running create-virtualenv step. 09:36:14 INFO - Running pre-action listener: _resource_record_pre_action 09:36:14 INFO - Running main action method: create_virtualenv 09:36:14 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:36:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:14 INFO - Using real prefix '/tools/python27' 09:36:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:15 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:18 INFO - Installing pip.................done. 09:36:18 INFO - Return code: 0 09:36:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:36:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:36:18 INFO - Reading from file tmpfile_stdout 09:36:18 INFO - Output received: 09:36:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:36:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:36:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:18 INFO - 'HOME': '/Users/cltbld', 09:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:18 INFO - 'LOGNAME': 'cltbld', 09:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:18 INFO - 'MOZ_NO_REMOTE': '1', 09:36:18 INFO - 'NO_EM_RESTART': '1', 09:36:18 INFO - 'PAGER': '/bin/cat', 09:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:18 INFO - 'PWD': '/builds/slave/test', 09:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:18 INFO - 'SHELL': '/bin/bash', 09:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:18 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:18 INFO - 'USER': 'cltbld', 09:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:18 INFO - 'XPC_FLAGS': '0x0', 09:36:18 INFO - 'XPC_SERVICE_NAME': '0', 09:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:36:18 INFO - Cleaning up... 09:36:18 INFO - Return code: 0 09:36:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:36:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:36:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:18 INFO - 'HOME': '/Users/cltbld', 09:36:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:18 INFO - 'LOGNAME': 'cltbld', 09:36:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:18 INFO - 'MOZ_NO_REMOTE': '1', 09:36:18 INFO - 'NO_EM_RESTART': '1', 09:36:18 INFO - 'PAGER': '/bin/cat', 09:36:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:18 INFO - 'PWD': '/builds/slave/test', 09:36:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:18 INFO - 'SHELL': '/bin/bash', 09:36:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:18 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:18 INFO - 'USER': 'cltbld', 09:36:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:18 INFO - 'XPC_FLAGS': '0x0', 09:36:18 INFO - 'XPC_SERVICE_NAME': '0', 09:36:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:19 INFO - Downloading/unpacking psutil>=3.1.1 09:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:22 INFO - Installing collected packages: psutil 09:36:22 INFO - Running setup.py install for psutil 09:36:22 INFO - building 'psutil._psutil_osx' extension 09:36:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:36:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:36:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:36:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:36:23 INFO - building 'psutil._psutil_posix' extension 09:36:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:36:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:36:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:36:23 INFO - ^ 09:36:23 INFO - 1 warning generated. 09:36:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:36:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:23 INFO - Successfully installed psutil 09:36:23 INFO - Cleaning up... 09:36:23 INFO - Return code: 0 09:36:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:36: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 mozsystemmonitor==0.3 09:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:23 INFO - 'HOME': '/Users/cltbld', 09:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:23 INFO - 'LOGNAME': 'cltbld', 09:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:23 INFO - 'MOZ_NO_REMOTE': '1', 09:36:23 INFO - 'NO_EM_RESTART': '1', 09:36:23 INFO - 'PAGER': '/bin/cat', 09:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:23 INFO - 'PWD': '/builds/slave/test', 09:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:23 INFO - 'SHELL': '/bin/bash', 09:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:23 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:23 INFO - 'USER': 'cltbld', 09:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:23 INFO - 'XPC_FLAGS': '0x0', 09:36:23 INFO - 'XPC_SERVICE_NAME': '0', 09:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:26 INFO - Installing collected packages: mozsystemmonitor 09:36:26 INFO - Running setup.py install for mozsystemmonitor 09:36:26 INFO - Successfully installed mozsystemmonitor 09:36:26 INFO - Cleaning up... 09:36:26 INFO - Return code: 0 09:36:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:26 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:36:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:26 INFO - 'HOME': '/Users/cltbld', 09:36:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:26 INFO - 'LOGNAME': 'cltbld', 09:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:26 INFO - 'MOZ_NO_REMOTE': '1', 09:36:26 INFO - 'NO_EM_RESTART': '1', 09:36:26 INFO - 'PAGER': '/bin/cat', 09:36:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:26 INFO - 'PWD': '/builds/slave/test', 09:36:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:26 INFO - 'SHELL': '/bin/bash', 09:36:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:26 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:26 INFO - 'USER': 'cltbld', 09:36:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:26 INFO - 'XPC_FLAGS': '0x0', 09:36:26 INFO - 'XPC_SERVICE_NAME': '0', 09:36:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:27 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:31 INFO - warning: no files found matching '*.txt' 09:36:31 INFO - no previously-included directories found matching 'build' 09:36:31 INFO - no previously-included directories found matching 'dist' 09:36:31 INFO - no previously-included directories found matching '.git*' 09:36:31 INFO - Installing collected packages: jsonschema, functools32 09:36:31 INFO - Running setup.py install for functools32 09:36:31 INFO - warning: no files found matching '*.txt' 09:36:31 INFO - no previously-included directories found matching 'build' 09:36:31 INFO - no previously-included directories found matching 'dist' 09:36:31 INFO - no previously-included directories found matching '.git*' 09:36:31 INFO - Successfully installed jsonschema functools32 09:36:31 INFO - Cleaning up... 09:36:31 INFO - Return code: 0 09:36:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:31 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:36:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:31 INFO - 'HOME': '/Users/cltbld', 09:36:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:31 INFO - 'LOGNAME': 'cltbld', 09:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:31 INFO - 'MOZ_NO_REMOTE': '1', 09:36:31 INFO - 'NO_EM_RESTART': '1', 09:36:31 INFO - 'PAGER': '/bin/cat', 09:36:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:31 INFO - 'PWD': '/builds/slave/test', 09:36:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:31 INFO - 'SHELL': '/bin/bash', 09:36:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:31 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:31 INFO - 'USER': 'cltbld', 09:36:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:31 INFO - 'XPC_FLAGS': '0x0', 09:36:31 INFO - 'XPC_SERVICE_NAME': '0', 09:36:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:36:31 INFO - Cleaning up... 09:36:31 INFO - Return code: 0 09:36:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:31 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:31 INFO - 'HOME': '/Users/cltbld', 09:36:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:31 INFO - 'LOGNAME': 'cltbld', 09:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:31 INFO - 'MOZ_NO_REMOTE': '1', 09:36:31 INFO - 'NO_EM_RESTART': '1', 09:36:31 INFO - 'PAGER': '/bin/cat', 09:36:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:31 INFO - 'PWD': '/builds/slave/test', 09:36:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:31 INFO - 'SHELL': '/bin/bash', 09:36:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:31 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:31 INFO - 'USER': 'cltbld', 09:36:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:31 INFO - 'XPC_FLAGS': '0x0', 09:36:31 INFO - 'XPC_SERVICE_NAME': '0', 09:36:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:35 INFO - Downloading docopt-0.6.1.tar.gz 09:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:36 INFO - Running setup.py install for blobuploader 09:36:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:36 INFO - Running setup.py install for requests 09:36:36 INFO - Running setup.py install for docopt 09:36:36 INFO - Successfully installed blobuploader requests docopt 09:36:36 INFO - Cleaning up... 09:36:36 INFO - Return code: 0 09:36:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:36 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:36:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:36:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:36 INFO - 'HOME': '/Users/cltbld', 09:36:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:36 INFO - 'LOGNAME': 'cltbld', 09:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:36 INFO - 'MOZ_NO_REMOTE': '1', 09:36:36 INFO - 'NO_EM_RESTART': '1', 09:36:36 INFO - 'PAGER': '/bin/cat', 09:36:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:36 INFO - 'PWD': '/builds/slave/test', 09:36:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:36 INFO - 'SHELL': '/bin/bash', 09:36:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:36 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:36 INFO - 'USER': 'cltbld', 09:36:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:36 INFO - 'XPC_FLAGS': '0x0', 09:36:36 INFO - 'XPC_SERVICE_NAME': '0', 09:36:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-z0OLlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-AMRYH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-p4JHf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-t3X_Pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-FQy3nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-6zsDxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-__ulxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eYL96l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:37 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-j_ptGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-z7lG_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7bP9bP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MYKSl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eD22VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-J7Ph_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-JNfpMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-It3uyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:38 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-LCFphO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:38 INFO - Running setup.py install for manifestparser 09:36:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:39 INFO - Running setup.py install for mozcrash 09:36:39 INFO - Running setup.py install for mozdebug 09:36:39 INFO - Running setup.py install for mozdevice 09:36:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:36:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:39 INFO - Running setup.py install for mozfile 09:36:39 INFO - Running setup.py install for mozhttpd 09:36:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:39 INFO - Running setup.py install for mozinfo 09:36:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:39 INFO - Running setup.py install for mozInstall 09:36:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Running setup.py install for mozleak 09:36:40 INFO - Running setup.py install for mozlog 09:36:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Running setup.py install for moznetwork 09:36:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Running setup.py install for mozprocess 09:36:40 INFO - Running setup.py install for mozprofile 09:36:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:40 INFO - Running setup.py install for mozrunner 09:36:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:41 INFO - Running setup.py install for mozscreenshot 09:36:41 INFO - Running setup.py install for moztest 09:36:41 INFO - Running setup.py install for mozversion 09:36:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:41 INFO - Cleaning up... 09:36:41 INFO - Return code: 0 09:36:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:41 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:36:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:36:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:41 INFO - 'HOME': '/Users/cltbld', 09:36:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:41 INFO - 'LOGNAME': 'cltbld', 09:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:41 INFO - 'MOZ_NO_REMOTE': '1', 09:36:41 INFO - 'NO_EM_RESTART': '1', 09:36:41 INFO - 'PAGER': '/bin/cat', 09:36:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:41 INFO - 'PWD': '/builds/slave/test', 09:36:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:41 INFO - 'SHELL': '/bin/bash', 09:36:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:41 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:41 INFO - 'USER': 'cltbld', 09:36:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:41 INFO - 'XPC_FLAGS': '0x0', 09:36:41 INFO - 'XPC_SERVICE_NAME': '0', 09:36:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8Occ9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-E2Q3Vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:41 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-vH6EHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8dgNqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kH2D5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-b3qTG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-vkKFfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eOU37x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MbA1C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-IldQmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-39tUyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:36:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:42 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CAXXPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:43 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)) 09:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:43 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-pphSTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:43 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-voxurF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:43 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Vhljgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:43 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-zuXCQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:43 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YTYwgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:36:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:46 INFO - Downloading blessings-1.6.tar.gz 09:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:46 INFO - Installing collected packages: blessings 09:36:46 INFO - Running setup.py install for blessings 09:36:46 INFO - Successfully installed blessings 09:36:46 INFO - Cleaning up... 09:36:46 INFO - Return code: 0 09:36:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:46 INFO - Reading from file tmpfile_stdout 09:36:46 INFO - Current package versions: 09:36:46 INFO - blessings == 1.6 09:36:46 INFO - blobuploader == 1.2.4 09:36:46 INFO - docopt == 0.6.1 09:36:46 INFO - functools32 == 3.2.3-2 09:36:46 INFO - jsonschema == 2.5.1 09:36:46 INFO - manifestparser == 1.1 09:36:46 INFO - mozInstall == 1.12 09:36:46 INFO - mozcrash == 1.0 09:36:46 INFO - mozdebug == 0.1 09:36:46 INFO - mozdevice == 0.49 09:36:46 INFO - mozfile == 1.2 09:36:46 INFO - mozhttpd == 0.7 09:36:46 INFO - mozinfo == 0.9 09:36:46 INFO - mozleak == 0.1 09:36:46 INFO - mozlog == 3.4 09:36:46 INFO - moznetwork == 0.27 09:36:46 INFO - mozprocess == 0.24 09:36:46 INFO - mozprofile == 0.28 09:36:46 INFO - mozrunner == 6.13 09:36:46 INFO - mozscreenshot == 0.1 09:36:46 INFO - mozsystemmonitor == 0.3 09:36:46 INFO - moztest == 0.8 09:36:46 INFO - mozversion == 1.4 09:36:46 INFO - psutil == 3.1.1 09:36:46 INFO - requests == 1.2.3 09:36:46 INFO - wsgiref == 0.1.2 09:36:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:48 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:36:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:48 INFO - 'HOME': '/Users/cltbld', 09:36:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:48 INFO - 'LOGNAME': 'cltbld', 09:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:48 INFO - 'MOZ_NO_REMOTE': '1', 09:36:48 INFO - 'NO_EM_RESTART': '1', 09:36:48 INFO - 'PAGER': '/bin/cat', 09:36:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:48 INFO - 'PWD': '/builds/slave/test', 09:36:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:48 INFO - 'SHELL': '/bin/bash', 09:36:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:48 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:48 INFO - 'USER': 'cltbld', 09:36:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:48 INFO - 'XPC_FLAGS': '0x0', 09:36:48 INFO - 'XPC_SERVICE_NAME': '0', 09:36:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:36:48 INFO - Cleaning up... 09:36:48 INFO - Return code: 0 09:36:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:48 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 0x108ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb793d9e010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, '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.sQHKQtTboK/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.it3FrhUaZH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:36:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:36:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:36:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:48 INFO - 'HOME': '/Users/cltbld', 09:36:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:48 INFO - 'LOGNAME': 'cltbld', 09:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:48 INFO - 'MOZ_NO_REMOTE': '1', 09:36:48 INFO - 'NO_EM_RESTART': '1', 09:36:48 INFO - 'PAGER': '/bin/cat', 09:36:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:48 INFO - 'PWD': '/builds/slave/test', 09:36:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:48 INFO - 'SHELL': '/bin/bash', 09:36:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:36:48 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:36:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:48 INFO - 'USER': 'cltbld', 09:36:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:48 INFO - 'XPC_FLAGS': '0x0', 09:36:48 INFO - 'XPC_SERVICE_NAME': '0', 09:36:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:36:49 INFO - Cleaning up... 09:36:49 INFO - Return code: 0 09:36:49 INFO - Running post-action listener: _resource_record_post_action 09:36:49 INFO - Running post-action listener: _start_resource_monitoring 09:36:49 INFO - Starting resource monitoring. 09:36:49 INFO - [mozharness: 2017-01-07 17:36:49.199425Z] Finished create-virtualenv step (success) 09:36:49 INFO - [mozharness: 2017-01-07 17:36:49.200511Z] Running install step. 09:36:49 INFO - Running pre-action listener: _resource_record_pre_action 09:36:49 INFO - Running main action method: install 09:36:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:49 INFO - Reading from file tmpfile_stdout 09:36:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:49 INFO - Reading from file tmpfile_stdout 09:36:49 INFO - Output received: 09:36:49 INFO - Usage: mozinstall [options] installer 09:36:49 INFO - Options: 09:36:49 INFO - -h, --help show this help message and exit 09:36:49 INFO - -d DEST, --destination=DEST 09:36:49 INFO - Directory to install application into. [default: 09:36:49 INFO - "/builds/slave/test"] 09:36:49 INFO - --app=APP Application being installed. [default: firefox] 09:36:49 INFO - mkdir: /builds/slave/test/build/application 09:36:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:37:11 INFO - Reading from file tmpfile_stdout 09:37:11 INFO - Output received: 09:37:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:37:11 INFO - Running post-action listener: _resource_record_post_action 09:37:11 INFO - [mozharness: 2017-01-07 17:37:11.539342Z] Finished install step (success) 09:37:11 INFO - [mozharness: 2017-01-07 17:37:11.539524Z] Running run-tests step. 09:37:11 INFO - Running pre-action listener: _resource_record_pre_action 09:37:11 INFO - Running main action method: run_tests 09:37:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:37:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:37:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:37:11 INFO - 2017-01-07 09:37:11.553 screenresolution[1962:14790] starting screenresolution argv=screenresolution get 09:37:11 INFO - 2017-01-07 09:37:11.556 screenresolution[1962:14790] Display 0: 1600x1200x32@60 09:37:11 INFO - 2017-01-07 09:37:11.562 screenresolution[1963:14793] starting screenresolution argv=screenresolution list 09:37:11 INFO - Available Modes on Display 0 09:37:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:37:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:37:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:37:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:37:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:37:11 INFO - Intel Iris: 09:37:11 INFO - Chipset Model: Intel Iris 09:37:11 INFO - Type: GPU 09:37:11 INFO - Bus: Built-In 09:37:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:37:11 INFO - Vendor: Intel (0x8086) 09:37:11 INFO - Device ID: 0x0a2e 09:37:11 INFO - Revision ID: 0x0009 09:37:11 INFO - Displays: 09:37:11 INFO - Display: 09:37:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:37:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:37:11 INFO - Display Serial Number: 1600x1200 60 09:37:11 INFO - Main Display: Yes 09:37:11 INFO - Mirror: Off 09:37:11 INFO - Online: Yes 09:37:11 INFO - Rotation: Supported 09:37:11 INFO - Return code: 0 09:37:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:37:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:37:11 INFO - Python 2.7.3 09:37:11 INFO - Return code: 0 09:37:11 INFO - grabbing minidump binary from tooltool 09:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:11 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 0x1088b7d30>, '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 0x1087dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088acbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:37:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:37:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:37:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:37:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:37:11 INFO - Return code: 0 09:37:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:37:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:37:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:37:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:37:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:37:11 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/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:37:11 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/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:37:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sQHKQtTboK/Render', 09:37:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:11 INFO - 'HOME': '/Users/cltbld', 09:37:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:11 INFO - 'LOGNAME': 'cltbld', 09:37:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:37:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:37:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:11 INFO - 'MOZ_NO_REMOTE': '1', 09:37:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:37:11 INFO - 'NO_EM_RESTART': '1', 09:37:11 INFO - 'PAGER': '/bin/cat', 09:37:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:11 INFO - 'PWD': '/builds/slave/test', 09:37:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:37:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:11 INFO - 'SHELL': '/bin/bash', 09:37:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.it3FrhUaZH/Listeners', 09:37:11 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:37:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:11 INFO - 'USER': 'cltbld', 09:37:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:11 INFO - 'XPC_FLAGS': '0x0', 09:37:11 INFO - 'XPC_SERVICE_NAME': '0', 09:37:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:11 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/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:37:12 INFO - mozversion application_buildid: 20170107082358 09:37:12 INFO - mozversion application_changeset: e9d16569a7b4518e3a90a0512128b838118dea55 09:37:12 INFO - mozversion application_display_name: Nightly 09:37:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:37:12 INFO - mozversion application_name: Firefox 09:37:12 INFO - mozversion application_remotingname: firefox 09:37:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:37:12 INFO - mozversion application_vendor: Mozilla 09:37:12 INFO - mozversion application_version: 53.0a1 09:37:12 INFO - mozversion platform_buildid: 20170107082358 09:37:12 INFO - mozversion platform_changeset: e9d16569a7b4518e3a90a0512128b838118dea55 09:37:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:37:12 INFO - mozversion platform_version: 53.0a1 09:37:12 INFO - using testdate: 1483810632 09:37:12 INFO - actual date: 1483810632 09:37:12 INFO - starting webserver on 'localhost:49669' 09:37:12 INFO - SUITE-START | Running 2 tests 09:37:12 INFO - TEST-START | dromaeo_css 09:37:12 INFO - operating with platform_type : mac_ 09:37:12 INFO - Initialising browser for dromaeo_css test... 09:37:12 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpjsFt6p/profile http://localhost:49669/getInfo.html) 09:37:12 INFO - PROCESS | 1973 | 1483810632664 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:37:14 INFO - PROCESS | 1973 | __metrics Screen width/height:1600/1200 09:37:14 INFO - PROCESS | 1973 | colorDepth:24 09:37:14 INFO - PROCESS | 1973 | Browser inner width/height: 1024/689 09:37:14 INFO - PROCESS | 1973 | __metrics 09:37:14 INFO - PROCESS | 1973 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:37:14 INFO - PROCESS | 1973 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:15 INFO - TEST-INFO | 1973: exit 0 09:37:15 INFO - Browser initialized. 09:37:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:37:15 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpjsFt6p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:27 INFO - PROCESS | 1975 | RSS: Main: 235089920 09:37:27 INFO - PROCESS | 1975 | 09:40:31 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49669/tests/dromaeo/cssquery-ext.html) 09:40:33 INFO - PROCESS | 1975 | RSS: Main: 370290688 09:40:33 INFO - PROCESS | 1975 | 09:42:16 INFO - PROCESS | 1975 | ************************* 09:42:16 INFO - PROCESS | 1975 | A coding exception was thrown and uncaught in a Task. 09:42:16 INFO - PROCESS | 1975 | 09:42:16 INFO - PROCESS | 1975 | Full message: ReferenceError: fetch is not defined 09:42:16 INFO - PROCESS | 1975 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:42:16 INFO - PROCESS | 1975 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:42:16 INFO - PROCESS | 1975 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:42:16 INFO - PROCESS | 1975 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:42:16 INFO - PROCESS | 1975 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:42:16 INFO - PROCESS | 1975 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:42:16 INFO - PROCESS | 1975 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:42:16 INFO - PROCESS | 1975 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:42:16 INFO - PROCESS | 1975 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:42:16 INFO - PROCESS | 1975 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:42:16 INFO - PROCESS | 1975 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:42:16 INFO - PROCESS | 1975 | 09:42:16 INFO - PROCESS | 1975 | ************************* 09:43:36 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-ext.html (next: http://localhost:49669/tests/dromaeo/cssquery-jquery.html) 09:43:37 INFO - PROCESS | 1975 | RSS: Main: 366022656 09:43:37 INFO - PROCESS | 1975 | 09:46:39 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49669/tests/dromaeo/cssquery-mootools.html) 09:46:40 INFO - PROCESS | 1975 | RSS: Main: 333701120 09:46:40 INFO - PROCESS | 1975 | 09:49:42 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49669/tests/dromaeo/cssquery-prototype.html) 09:49:43 INFO - PROCESS | 1975 | RSS: Main: 336568320 09:49:43 INFO - PROCESS | 1975 | 09:52:46 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49669/tests/dromaeo/cssquery-yui.html) 09:52:47 INFO - PROCESS | 1975 | RSS: Main: 330252288 09:52:47 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:49669/tests/dromaeo/cssquery-yui.html (next: http://localhost:49669/tests/dromaeo/cssquery-dojo.html) 09:55:50 INFO - PROCESS | 1975 | RSS: Main: 392589312 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | __start_tp_report 09:55:50 INFO - PROCESS | 1975 | _x_x_mozilla_page_load 09:55:50 INFO - PROCESS | 1975 | _x_x_mozilla_page_load_details 09:55:50 INFO - PROCESS | 1975 | |i|pagename|runs| 09:55:50 INFO - PROCESS | 1975 | |0;dojo.html;3254.6419893811685;3258.185453636591;3269.9509507357407;3302.256992176759;3312.7184189343902;5413.215083812855;5512.448755124486;5534.501894829465;5551.972240138799;5589.86025349367;5471.835844924649;5553.250311207986;5607;5643.492085712285;5668.57485688574;4951.133551628469;4978.751062446863;4994.500549945013;5035.018171456549;5070.797168113271;5077.035363280965;5169.896602067956;5185.170372740362;5191.065608190543;5243.082460569404;5039.992001599658;5169.922451163236;5182.119039763212;5189.584833213334;5207.921881171786;5051.393832740058;5104.668196567211;5104.923426148611;5108.335916330853;5109.565686916617;5032.47159048302;5053.823116190915;5078.619103567247;5083.38999320084;5087.618428617869;5507.752151153168;5522.723863806794;5525.364583072957;5531.751071201806;5538.556915446795;6069.939300607026;6076.210092687922;6076.513878889678;6085.36103709113;6096.7561297548045;6093.238345206849;6117.562731150785;6121.326654068049;6131.407250743203;6138.1099740537375;5613.803516876889;5649.209110724501;5661.207430959669;5706.657600543981;5713.485786279255;6231.968840155861;6256.624602523863;6270.655113968775;6272.059191121277;6301.77943771975;5841.474267315962;5899.351071382144;5974.910376344358;5978;5990.64056156632;6114.640808386383;6123.050927106306;6129;6129.203203583595;6139.2325959255095;6019.939800602026;6031.185789918392;6066.848328791727;6076.149339092531;6126.571140020245;6155.599886007395;6156.630602163885;6157.353477884847;6159.969200153971;6165.075238714228;4835.371401717754;4843.321934929113;4847.684900481458;4865.854024379274;4871.196252618349;4216.262154123077;4259.446271984684;4263.211305908417;4277.037666525007;4292.849750258789;5735.655860648291;5743.62666426681;5745.368009518949;5751.338596061507;5803.883922321615;6184.165137706352;6205.844853878689;6225.564210505221;6230.719617617218;6241.21984751906;5586.55307575393;5621.550275977912;5647.407022262685;5716.79991200306;5724.914126288026;4631.536846315409;5278.630495865329;5318.840434786963;5346.8395948121615;5370.91943620846;6256.687165641791;6263;6267.2479302484;6274.964630835861;6285.905711414424;6019.759209631566;6241.407066328691;6246.625202487865;6255.46828519581;6262;6248.094026366242;6250.312465628686;6291.811245662638;6303.684815759286;6310.432061114522;6049.18336024631;6056.152138700498;6061;6067.059605761114;6070.6357618543025;5983.95280825845;6017.338092809699;6043.033114701627;6112.108359148444;6129.08063790435;5868.767558812697;5869.266341707286;5870.5010074144175;5878.441548052928;5911.172435859069;5843.94156058451;5851.093080572347;5864.853378665397;5865.237519122486;5870.7945221917025;5622.353429355677;5652.7738890443925;5687.20379146928;5694.715264236855;5723.284589426322;5786.450287222707;5827.825165245049;5829.53363730892;5851.297844258716;5876.08920617305;5673.574481913791;5678.602497825278;5686.744096515749;5695.772169113189;5702.059160238514 09:55:50 INFO - PROCESS | 1975 | |1;ext.html;454.97403355433494;477.4461624515562;483.6856043571678;494.053886806765;507.35565831394155;30555;39724;39744.80127599362;39759.60240397595;39760;40339.59660403395;40766.79616601913;40908.59091409085;40931.18137637246;40937.18125637485;8549.230569248766;8738.86891696622;9246.306527010467;9469.905300946972;9486.478243696629;11187.049100826398;11523.942380288086;11524.942375288154;11584.015358694522;11727.237729547553;11412.429378531082;11430.142739294544;11519.884801152008;11528.827067593993;11546.942265288704;8579.399442039059;8780.165884240987;11438.542458301657;11519.94240028803;11521.423928803526;11227.157963152715;11522.251053681484;11536.596219132374;11566.132540059445;11566.47950842214;32315.51526727101;39976.200475990605;40027.19945601072;40098.79950600257;40213.79893100545;4037;4038.717289789716;4043.1913617276664;4059.9391009134592;4200.978995105005;29955.850220748976;30131.096067117804;30235.546466803014;30936.845315773502;31025.37949241025;3003.6996300370006;3048.939021219563;3067.9233019174476;3152.2592190835135;3244.707976282122;410237;459553;462523.6873815609;463953;464430.677846612;57391.71304143536;57622;57701;57760;57909.55226119296;60397;60427.69786151041;60587.69706151441;60621.4844628879;60712.69643651842;15344.923275383551;15828.920855395649;15881.841181588035;15884;15922.60193495172;11163.553457861757;11426;12140.696482588006;12361.825626565462;12450.626481205578;14639.707205856037;15045.623859403384;15263.694726105638;15389.769153462707;15414;6414.486841052704;6456.289808121103;6478.319776423407;6486.189226346707;6527.738890444329;857.339848316805;887.6671248281895;892.4957399069505;917.3211823250747;920.8848893888264;29317;29411.705882940896;29417.11748647544;30121.548176777367;30158.793648254265;1049.9580016799396;1066.3921564708041;1101.6034227678028;1119.5242022140558;1187.6080893305189;12913;15222.086674799326;15326.770098448533;15405.537833865003;15576.688466230611;595524.0447595469;598656;598934;599213;601469;58731;59086.40913590895;59106.817863642485;59240;59290;1422.879055280292;1458.3874772595536;1477.475496198877;1480.9333579988927;1485.9925700371646;24290.51418971646;27401;28575.571366429103;29265.561016584343;29358;45471.77264113658;48525.757371212916;48617.51382486271;48639.02721945612;48748;501139;502493;503086;503539;503768;37738;38122.42816357702;38427.615723842406;38500.42249366206;38524.11069169767;44959.32561011522;45367.7731611353;45665.40171094129;46262.53737462583;46592.30111548397;31965.36069278648;36864.26271474609;37292.254154916205;37297.440538391405;38185.6181438182;30426.69573304327;36900.630993689716;37517.81241093777;38533.807330963165;38846.6115338843;34667.653323466446;35312;36775;37580.436293456165;37750;26017.6097358536;30941.7286937574;31163.688363116078;31344.843275783474;33426.49860252049;22748.545029099656;23863.522729544966;24176;24515.75484245206;24612.261632151065 09:55:50 INFO - PROCESS | 1975 | |2;jquery.html;361.4109002326208;381.2280132731221;387.4188716924613;394.57583098169476;417.7159531518568;15339.53981380559;15376.846231537682;18578.349757758482;20575.27986520472;20729.06719197636;21005.36983890482;21012.94935253239;21316;21450;21608.675869861963;5689.6017278790505;5840.386759390267;5951.285845698522;6101.328853826098;6115.4190351916495;5890.676012819294;5913.9112913306335;5924.2298501195;5932.644041357511;5940.405959404068;3521.6478352164836;3867.4005529142887;4037.2934736421157;4064.5732198119217;6477.417032467077;6546.509011824109;6563.93436065638;6564.179477565304;6573.112629795012;6576.309487503839;5619.859503512404;5752.482276595085;5804.2744656917885;5808.680522571257;5813.069908814569;13124;13291.800622990662;14399.640008999753;24379.512409751882;24772.5045499089;2540.9237722868343;2980.8658610362586;2995.6405231372373;3001;3056.2206637307568;9998.850017249675;10364;10403.375797452101;13312.400941957538;13974.720505589932;1762.8765986380959;1800.19891148439;1816.3551939061574;1827.0590645817415;1839.9356022539148;96228.51885740597;130578;131515;131783.02325465222;131811;9693.321467497208;9945.403275803475;10319.793604127875;10358.741031474123;10425.530851111718;10800.891991080145;12584.874151258553;12682.302473364054;12703.30131842739;12705.950571862686;7893.644785984529;8093.635786389626;8112.959435202786;8193.754187374388;8237.46456480327;8300.335973122135;8311.459755115899;8328.125546817497;8452.366072544584;8486.660533578588;4007.0783719744622;4008.7594744315434;4009.278329900588;4029.838806447768;4075.144219713893;1693.8729595280404;1958.9911195734207;1999.780024197337;2002.7696814866476;2003.4390370696228;386.29693956998557;408.8814243869304;421.9894502637459;423.1177993882789;1027.2860362048305;11793.056555475541;11967.760644787055;12054.09594280415;12119.939400302943;12201.938990304992;358.75246080204573;447.7112262590611;448.5447271019917;454;455.2738382280258;5977.342492325841;6004.309504406961;6058.363871793486;6150.815475535741;6205.968970155211;605601;712570;726211;735354.0504329398;736843;115381;127176.72823271649;146614;148523.05907763785;154301;1067.6423398161703;1103.332483847277;1124.6344937895217;1129.7119234595125;1135.6138912769534;18745.156467958703;19556.82659040462;19559.119839607538;19604.607907841477;19663.8033619662;56481.71759141178;57010;57980;58910.3490342927;58926;78037;83636.58181709053;84755;85113;86085.13914860772;6892.379685828299;7288.453365997465;7372.815679608053;7483.738069167553;7749.302562769378;10711.410872402093;10735.355878647306;12758.90390502494;12907.096503244682;13009.21944683322;10746.83879741789;10837.783244335227;11196.720081998015;11294.435278235891;11297.43512824372;10825.512851921525;10865.782684346426;11064.944675276573;11242.032644287054;11281.32312061279;11131.610393636183;11168.553257869919;11605.825912611148;11648.242864213798;11679.124065695276;7299.343059124704;7365.410767138509;7374.705011799468;7518.586477743776;7563.848723025619;9832.459214742972;10945.781084378426;11315.660530184108;11334.433278336215;11344.489497972776 09:55:50 INFO - PROCESS | 1975 | |3;mootools.html;1473.6536913825248;1473.9705205895875;1491.2022068193517;1500.699860027994;1506.47273454291;7040.7183712651495;8446.49321040738;8467.872981905268;8469.618867150977;8471.110533393981;7414.555126692402;7681.615919204031;7704.88442673359;8140.104588495261;8375.20435558621;2301.976980230201;2338.6141286687675;2339.8596084234914;2341.0518739910362;2346.5424242272766;2261.0955617752948;2292.426893276681;2295.0704964489323;2332.9416764580938;2352.6588644646517;2296.357020034393;2296.391456264083;2323.9883800580974;2326.325365643961;2327.1622216002224;2280.942976425594;2293.7820907013806;2294.3461113582634;2316.1661801751357;2327.755585663515;2272.204728345066;2307.261676263596;2308.0883051194683;2314.0396735354975;2323.1520495019367;8364.456310339809;8433.704820331259;8440.57797110142;8441.451305665112;8449.112843151443;4952.826651067231;5136.845894623168;5146.511081447256;5147.22791581262;5148.227765835118;3273.885414010498;3402.8638854445794;3509.543759311299;3546.8935931922083;3550.414181660023;1190.8451901252868;1200.8498937632796;1237.560665963579;1252.4364036183663;1270.0855384123417;9023.323250756219;10744.838827417518;10789.730256743565;10790.5683772649;10882.40146791926;4349.934750978738;4482.305242687388;4556.863294101182;4678.111158879802;4684.531546845356;9389.295802814817;9395.107464790834;9396.295277854051;9407.858882116774;9411.482368469662;5126.333576635014;5126.410462796826;5174.223866420067;5203.791848326025;5211.921821172686;4255.085156691264;4392.846250381222;4424.469063712375;4700.976495117503;4747.216709243005;3712.1833196696684;3752.418375151856;3847.1920896611464;3856.1709232515145;3898.5321761388814;1264.6395777203452;1265.1966001589058;1266.0631132961726;1283.8587755346905;1289.232906420678;806.2542148512603;836.8284501677142;843.308487040633;876.7900297589285;884.0717246890739;4628.912205631674;4690.64820138491;4720.12677654635;4735.431748190171;4808.855734327975;562.2634349002738;960.9957594313959;997.2670087485692;1001.3191030099457;1032.2774058159318;3563.893083207508;3669.8348574313686;3714.270011541515;3758.830852611639;3804.7907365094634;7780.610969451504;9755.268354873271;9857.457839818879;9859.161971232383;9876.80246395068;9808.754781130387;9814.656487023063;9817.214622830157;9835.704928852147;9857.309988300893;7680.231976802387;7750.7674769756995;7762.2237776223055;7843.686112596798;7856.450048496665;5163.870903227449;5432.891342173055;5460.945390546202;5465.672059676433;5528.341497550799;9026.638934442548;9049.683261085831;9100.180983711232;9114.726558203265;9200.861987070333;8980.191782739585;9218.400803947721;9252.444853308823;9327.300452466228;9373.81252374935;6657.7669781557415;6872.62200578973;7168;7309.8903516446235;7509.098908131059;7848.9215107848195;7943.523388596703;8036.718714844952;8062.234087761653;8063.153368896416;6975.337342952412;6995.335443132894;7061.470389720689;7110.288971102848;7260.128784545888;6905.136857892763;6954.860902782018;6977.895331569929;7092.787216383516;7120.145582530129;6301.275353334425;6334.3665633436285;6405.263394709482;6407.2631647359285;6410.0705397718;4888.877778055577;4914.950850491449;4942.5304596063315;4963.255511673278;5047.64666473358;4603.769811509478;4897.436794768647;4941.308216849716;4997.750112494434;5015.774290156879 09:55:50 INFO - PROCESS | 1975 | |4;prototype.html;347.08542989223395;347.2638007424261;354.19950910941276;368.7934756536342;370.62196559509283;4543.954560454395;4624.653151013671;5171.767270472828;5185.896282074356;5210.452902445246;4629.574115154786;4670.944784294917;4869.488703686106;4935.284383764352;5129.615003948931;3859.9035024124482;4049.02823322402;4051.392291156321;4062.939055914164;4076.490438695163;3607.6572725591027;3969.5435024972066;4000.219957108365;4070.931690045897;4072.3077076896852;3439.914002149954;3832.7508711933633;4010.6189911958318;4051.7771522566245;4108.979455102721;3609.819509024538;4000.739951903146;4028.798560071985;4051.5543290238197;4063.1264278180183;3186.2193762528095;3425.6231814500384;3429.9828500857584;3619.7647152934974;3620.6379362063844;4792.520747925215;5045.394552653705;5052.9242061369105;5061.670991385548;5067.746612669352;4184.665226781881;4407.60331570157;4667.432911898442;4672;4703.576678098953;4145;4416.403785488982;5420.512153906167;5420.7018613976215;5420.945790542083;3969.444277801081;3969.503812023497;3971.663802405964;4005.939910901339;4020.396940458925;4576.565226303496;4836.975815120937;5403.729813509309;5633.943660563383;5663.603547751659;3623.9818800905828;3925.9203718976937;4061.2080644274083;4073.6130067643526;4073.6944729145434;3506.649335066524;3810.123671555558;3923.156521347924;4134.64855487281;4142.668586513072;4339.349097635317;4379.401518445744;4451.287793953017;4673.369095172108;4729.385179926588;4654.371659825948;5182.922256166161;5436.782528698887;5448.291722076184;5467.917981230285;1055.3140458701753;1289.6711338608595;1316.2957817567747;1328.1074468881804;1329.415057374752;897.869808877709;911.7128104647015;939.910708482693;964.9482064549675;971.2424309038961;4256.34026725858;4705.364775755298;4751.524847515221;4754.500777418321;4825.252085926617;4326.848560300375;4696.577308042224;5229.529342359206;5248.685078895279;5280.788768449296;4210.221109094828;4429.335599660077;4455.888602784891;4716.834910778106;4796.736179510091;2389.411041657313;2491.7383674714256;2741.999170302825;2744.423671029106;2770.337797321591;4387.802548885308;4499.887502812456;5038.84883453497;5224.242484839677;5441.421987623624;5010;5018.787975695558;5156.845294641167;5398.568114550826;5428.37573679032;4583.335416364608;4712.269598212282;5289.96845615109;5438.728063596725;5439.259436980207;4487.596116349544;4614.077184563033;5104.3619547556555;5165.638405311592;5208.401033881152;4736.526347365236;5152.536271735412;5158.767855446439;5190.247414125006;5202.947970520247;4904.485029072039;5218.243354713622;5334.679414454725;5408.972955135199;5440.836774896759;3278.09852290622;3455.1016735649414;3470.35798377301;3715.6841668458483;3810.5236845394325;4981.825636102719;5037.798488060436;5173.32746742921;5226.529612334908;5564.888702226014;4867.931129270117;5191;5409.972950135224;5415.323084614423;5428.647137936022;5188.948110518846;5410.59420543453;5410.943841340423;5416.702081385404;5478.8904221914545;5035.827428719883;5259.895421961431;5314.867128321823;5331.4805280494875;5338.46615338463;4597.540245975376;5200.245964335075;5275.920861187162;5306.761195746123;5360.276362690986;4881.926771098508;5287.836675931289;5295.655782374133;5312.62811603184;5338.279332290246 09:55:50 INFO - PROCESS | 1975 | |5;yui.html;725.0791494801603;759.0587671287598;781.1173374087281;796.1839114907219;847.4788005376695;14421.69252919533;15143.62140946477;15196.240187990585;15211.391544338227;15215.315310811013;11953.820692689595;14319;14489.203093829836;14518.927405362985;14526.709465810678;662.0896267631994;683.9829004274883;688.215434404779;854.8845905802731;879.7800549862534;867.9001914779786;873.8558137907238;885.663447889801;886.5921676029035;888.3914518554778;866.7746385939638;901.3555307954811;943.2642538819697;947.6304241345881;952.1668540027476;673.737242475435;697.4072038767058;936.148105224246;945.7682867697424;946.068122898944;547.4334064243528;663.9933600663979;672.6872004517874;689.8034060292792;691.8512519808215;14226;14382.92808535961;14441.133531988115;14451.855481445156;14477;13696.863031369658;13744.518941836988;13797.862021379759;13803;13823.51617693386;10327.431991240479;12223.205491643104;12258.877411225863;12270.631881043493;12526.561570345086;4235.369382787618;4247.554006829269;4296.226679197744;4379.868603941887;4402.977985110086;72142;100357.49821250846;101499.49250253702;102674.48662756638;103207.4839625797;10507.159427245802;10810.29733067348;11083.113350931906;11110.722231944266;11301;21541.569168616854;21544.922753862244;21555.353339399844;21569.78430215678;21581.67627485589;6917.342852429011;7103.183133939559;7130.322619351154;7145.714171433188;7278.599677017709;4247.3628955656895;4322.265214913409;4332.090261045216;4339.327404252346;4340.045190058182;3428.7599868009497;3488.633693462201;3500.824958752052;3527.8236088195486;3532.8410221540094;629.288903538998;633.2622494793513;637.2544123375661;644.5552568727563;646.4537465841356;635.1911766891841;639.834161363787;653.1052458132389;656.4321861589691;681.6216999565289;9604.375715578464;9688.70933871985;9754.268429867789;9862.65480708186;10041.548130334006;571.9571032172604;582.1645938078871;593.1340243244824;624.7032659486798;687.7730348984823;4603.355530225771;4929.630277729185;4987.775550100254;5068.6451948364;5076.416212135578;74750;75067;83781.58109209414;87658.56170719233;88659.55670221608;65557;83792;84459;84840;86430;59527.70236148878;66326.66836665882;66521;67144.66427667928;67247;7502.587357695161;9392;9428.057194280516;9636.71089867305;9731.854022189531;42982.35526467043;45320.093598128515;46035;47009.3546725863;50431.747841260556;63705;65171.67414163089;66944.6652766733;69697.9545306831;69753;9054.456732596065;10628.52171652293;10719.249652524248;10891.836622450512;11230.66308010761;12157.331346776013;12184.939075304566;12365.505379784709;12371.319577423328;12819.294938778457;11718.589849355232;11801.940990294994;12080.577179798665;12315.07636927252;12339.75320493603;11784;11898.524059037887;11918.940405298265;11987.460564274454;11996.100292478273;11858.407079646156;11859.34773587461;11866.82199766987;11948.880511194777;11951.043916486835;9686.612535498783;9994.500274986367;10010.8498372523;10108.797824043331;10133.746656333651;9580.13778759915;9621.51892405363;9647.71056868295;9654.179394751243;9658.9034109658 09:55:50 INFO - PROCESS | 1975 | __end_tp_report 09:55:50 INFO - PROCESS | 1975 | __start_cc_report 09:55:50 INFO - PROCESS | 1975 | _x_x_mozilla_cycle_collect,2341 09:55:50 INFO - PROCESS | 1975 | __end_cc_report 09:55:50 INFO - PROCESS | 1975 | __startTimestamp1483811750632__endTimestamp 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | ------- Summary: start ------- 09:55:50 INFO - PROCESS | 1975 | Number of tests: 6 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | [#0] dojo.html Cycles:165 Average:5623.77 Median:5745.37 stddev:641.09 (11.2%) stddev-sans-first:615.53 09:55:50 INFO - PROCESS | 1975 | Values: 3254.6 3258.2 3270.0 3302.3 3312.7 5413.2 5512.4 5534.5 5552.0 5589.9 5471.8 5553.3 5607.0 5643.5 5668.6 4951.1 4978.8 4994.5 5035.0 5070.8 5077.0 5169.9 5185.2 5191.1 5243.1 5040.0 5169.9 5182.1 5189.6 5207.9 5051.4 5104.7 5104.9 5108.3 5109.6 5032.5 5053.8 5078.6 5083.4 5087.6 5507.8 5522.7 5525.4 5531.8 5538.6 6069.9 6076.2 6076.5 6085.4 6096.8 6093.2 6117.6 6121.3 6131.4 6138.1 5613.8 5649.2 5661.2 5706.7 5713.5 6232.0 6256.6 6270.7 6272.1 6301.8 5841.5 5899.4 5974.9 5978.0 5990.6 6114.6 6123.1 6129.0 6129.2 6139.2 6019.9 6031.2 6066.8 6076.1 6126.6 6155.6 6156.6 6157.4 6160.0 6165.1 4835.4 4843.3 4847.7 4865.9 4871.2 4216.3 4259.4 4263.2 4277.0 4292.8 5735.7 5743.6 5745.4 5751.3 5803.9 6184.2 6205.8 6225.6 6230.7 6241.2 5586.6 5621.6 5647.4 5716.8 5724.9 4631.5 5278.6 5318.8 5346.8 5370.9 6256.7 6263.0 6267.2 6275.0 6285.9 6019.8 6241.4 6246.6 6255.5 6262.0 6248.1 6250.3 6291.8 6303.7 6310.4 6049.2 6056.2 6061.0 6067.1 6070.6 5984.0 6017.3 6043.0 6112.1 6129.1 5868.8 5869.3 5870.5 5878.4 5911.2 5843.9 5851.1 5864.9 5865.2 5870.8 5622.4 5652.8 5687.2 5694.7 5723.3 5786.5 5827.8 5829.5 5851.3 5876.1 5673.6 5678.6 5686.7 5695.8 5702.1 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | [#1] ext.html Cycles:180 Average:65588.84 Median:37659.22 stddev:139019.47 (369.2%) stddev-sans-first:139323.45 09:55:50 INFO - PROCESS | 1975 | Values: 455.0 477.4 483.7 494.1 507.4 30555.0 39724.0 39744.8 39759.6 39760.0 40339.6 40766.8 40908.6 40931.2 40937.2 8549.2 8738.9 9246.3 9469.9 9486.5 11187.0 11523.9 11524.9 11584.0 11727.2 11412.4 11430.1 11519.9 11528.8 11546.9 8579.4 8780.2 11438.5 11519.9 11521.4 11227.2 11522.3 11536.6 11566.1 11566.5 32315.5 39976.2 40027.2 40098.8 40213.8 4037.0 4038.7 4043.2 4059.9 4201.0 29955.9 30131.1 30235.5 30936.8 31025.4 3003.7 3048.9 3067.9 3152.3 3244.7 410237.0 459553.0 462523.7 463953.0 464430.7 57391.7 57622.0 57701.0 57760.0 57909.6 60397.0 60427.7 60587.7 60621.5 60712.7 15344.9 15828.9 15881.8 15884.0 15922.6 11163.6 11426.0 12140.7 12361.8 12450.6 14639.7 15045.6 15263.7 15389.8 15414.0 6414.5 6456.3 6478.3 6486.2 6527.7 857.3 887.7 892.5 917.3 920.9 29317.0 29411.7 29417.1 30121.5 30158.8 1050.0 1066.4 1101.6 1119.5 1187.6 12913.0 15222.1 15326.8 15405.5 15576.7 595524.0 598656.0 598934.0 599213.0 601469.0 58731.0 59086.4 59106.8 59240.0 59290.0 1422.9 1458.4 1477.5 1480.9 1486.0 24290.5 27401.0 28575.6 29265.6 29358.0 45471.8 48525.8 48617.5 48639.0 48748.0 501139.0 502493.0 503086.0 503539.0 503768.0 37738.0 38122.4 38427.6 38500.4 38524.1 44959.3 45367.8 45665.4 46262.5 46592.3 31965.4 36864.3 37292.3 37297.4 38185.6 30426.7 36900.6 37517.8 38533.8 38846.6 34667.7 35312.0 36775.0 37580.4 37750.0 26017.6 30941.7 31163.7 31344.8 33426.5 22748.5 23863.5 24176.0 24515.8 24612.3 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | [#2] jquery.html Cycles:180 Average:37944.76 Median:3288.93 stddev:117448.95 (3571.0%) stddev-sans-first:117744.52 09:55:50 INFO - PROCESS | 1975 | Values: 361.4 381.2 387.4 394.6 417.7 15339.5 15376.8 18578.3 20575.3 20729.1 21005.4 21012.9 21316.0 21450.0 21608.7 5689.6 5840.4 5951.3 6101.3 6115.4 5890.7 5913.9 5924.2 5932.6 5940.4 3521.6 3867.4 4037.3 4064.6 6477.4 6546.5 6563.9 6564.2 6573.1 6576.3 5619.9 5752.5 5804.3 5808.7 5813.1 13124.0 13291.8 14399.6 24379.5 24772.5 2540.9 2980.9 2995.6 3001.0 3056.2 9998.9 10364.0 10403.4 13312.4 13974.7 1762.9 1800.2 1816.4 1827.1 1839.9 96228.5 130578.0 131515.0 131783.0 131811.0 9693.3 9945.4 10319.8 10358.7 10425.5 10800.9 12584.9 12682.3 12703.3 12706.0 7893.6 8093.6 8113.0 8193.8 8237.5 8300.3 8311.5 8328.1 8452.4 8486.7 4007.1 4008.8 4009.3 4029.8 4075.1 1693.9 1959.0 1999.8 2002.8 2003.4 386.3 408.9 422.0 423.1 1027.3 11793.1 11967.8 12054.1 12119.9 12201.9 358.8 447.7 448.5 454.0 455.3 5977.3 6004.3 6058.4 6150.8 6206.0 605601.0 712570.0 726211.0 735354.1 736843.0 115381.0 127176.7 146614.0 148523.1 154301.0 1067.6 1103.3 1124.6 1129.7 1135.6 18745.2 19556.8 19559.1 19604.6 19663.8 56481.7 57010.0 57980.0 58910.3 58926.0 78037.0 83636.6 84755.0 85113.0 86085.1 6892.4 7288.5 7372.8 7483.7 7749.3 10711.4 10735.4 12758.9 12907.1 13009.2 10746.8 10837.8 11196.7 11294.4 11297.4 10825.5 10865.8 11064.9 11242.0 11281.3 11131.6 11168.6 11605.8 11648.2 11679.1 7299.3 7365.4 7374.7 7518.6 7563.8 9832.5 10945.8 11315.7 11334.4 11344.5 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | [#3] mootools.html Cycles:180 Average:5356.07 Median:5146.87 stddev:2904.76 (56.4%) stddev-sans-first:2898.26 09:55:50 INFO - PROCESS | 1975 | Values: 1473.7 1474.0 1491.2 1500.7 1506.5 7040.7 8446.5 8467.9 8469.6 8471.1 7414.6 7681.6 7704.9 8140.1 8375.2 2302.0 2338.6 2339.9 2341.1 2346.5 2261.1 2292.4 2295.1 2332.9 2352.7 2296.4 2296.4 2324.0 2326.3 2327.2 2280.9 2293.8 2294.3 2316.2 2327.8 2272.2 2307.3 2308.1 2314.0 2323.2 8364.5 8433.7 8440.6 8441.5 8449.1 4952.8 5136.8 5146.5 5147.2 5148.2 3273.9 3402.9 3509.5 3546.9 3550.4 1190.8 1200.8 1237.6 1252.4 1270.1 9023.3 10744.8 10789.7 10790.6 10882.4 4349.9 4482.3 4556.9 4678.1 4684.5 9389.3 9395.1 9396.3 9407.9 9411.5 5126.3 5126.4 5174.2 5203.8 5211.9 4255.1 4392.8 4424.5 4701.0 4747.2 3712.2 3752.4 3847.2 3856.2 3898.5 1264.6 1265.2 1266.1 1283.9 1289.2 806.3 836.8 843.3 876.8 884.1 4628.9 4690.6 4720.1 4735.4 4808.9 562.3 961.0 997.3 1001.3 1032.3 3563.9 3669.8 3714.3 3758.8 3804.8 7780.6 9755.3 9857.5 9859.2 9876.8 9808.8 9814.7 9817.2 9835.7 9857.3 7680.2 7750.8 7762.2 7843.7 7856.5 5163.9 5432.9 5460.9 5465.7 5528.3 9026.6 9049.7 9100.2 9114.7 9200.9 8980.2 9218.4 9252.4 9327.3 9373.8 6657.8 6872.6 7168.0 7309.9 7509.1 7848.9 7943.5 8036.7 8062.2 8063.2 6975.3 6995.3 7061.5 7110.3 7260.1 6905.1 6954.9 6977.9 7092.8 7120.1 6301.3 6334.4 6405.3 6407.3 6410.1 4888.9 4915.0 4942.5 4963.3 5047.6 4603.8 4897.4 4941.3 4997.8 5015.8 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | [#4] prototype.html Cycles:180 Average:4310.69 Median:4714.55 stddev:1254.17 (26.6%) stddev-sans-first:1221.89 09:55:50 INFO - PROCESS | 1975 | Values: 347.1 347.3 354.2 368.8 370.6 4544.0 4624.7 5171.8 5185.9 5210.5 4629.6 4670.9 4869.5 4935.3 5129.6 3859.9 4049.0 4051.4 4062.9 4076.5 3607.7 3969.5 4000.2 4070.9 4072.3 3439.9 3832.8 4010.6 4051.8 4109.0 3609.8 4000.7 4028.8 4051.6 4063.1 3186.2 3425.6 3430.0 3619.8 3620.6 4792.5 5045.4 5052.9 5061.7 5067.7 4184.7 4407.6 4667.4 4672.0 4703.6 4145.0 4416.4 5420.5 5420.7 5420.9 3969.4 3969.5 3971.7 4005.9 4020.4 4576.6 4837.0 5403.7 5633.9 5663.6 3624.0 3925.9 4061.2 4073.6 4073.7 3506.6 3810.1 3923.2 4134.6 4142.7 4339.3 4379.4 4451.3 4673.4 4729.4 4654.4 5182.9 5436.8 5448.3 5467.9 1055.3 1289.7 1316.3 1328.1 1329.4 897.9 911.7 939.9 964.9 971.2 4256.3 4705.4 4751.5 4754.5 4825.3 4326.8 4696.6 5229.5 5248.7 5280.8 4210.2 4429.3 4455.9 4716.8 4796.7 2389.4 2491.7 2742.0 2744.4 2770.3 4387.8 4499.9 5038.8 5224.2 5441.4 5010.0 5018.8 5156.8 5398.6 5428.4 4583.3 4712.3 5290.0 5438.7 5439.3 4487.6 4614.1 5104.4 5165.6 5208.4 4736.5 5152.5 5158.8 5190.2 5202.9 4904.5 5218.2 5334.7 5409.0 5440.8 3278.1 3455.1 3470.4 3715.7 3810.5 4981.8 5037.8 5173.3 5226.5 5564.9 4867.9 5191.0 5410.0 5415.3 5428.6 5188.9 5410.6 5410.9 5416.7 5478.9 5035.8 5259.9 5314.9 5331.5 5338.5 4597.5 5200.2 5275.9 5306.8 5360.3 4881.9 5287.8 5295.7 5312.6 5338.3 09:55:50 INFO - PROCESS | 1975 | 09:55:50 INFO - PROCESS | 1975 | [#5] yui.html Cycles:180 Average:18543.17 Median:5072.53 stddev:25911.31 (510.8%) stddev-sans-first:25949.45 09:55:50 INFO - PROCESS | 1975 | Values: 725.1 759.1 781.1 796.2 847.5 14421.7 15143.6 15196.2 15211.4 15215.3 11953.8 14319.0 14489.2 14518.9 14526.7 662.1 684.0 688.2 854.9 879.8 867.9 873.9 885.7 886.6 888.4 866.8 901.4 943.3 947.6 952.2 673.7 697.4 936.1 945.8 946.1 547.4 664.0 672.7 689.8 691.9 14226.0 14382.9 14441.1 14451.9 14477.0 13696.9 13744.5 13797.9 13803.0 13823.5 10327.4 12223.2 12258.9 12270.6 12526.6 4235.4 4247.6 4296.2 4379.9 4403.0 72142.0 100357.5 101499.5 102674.5 103207.5 10507.2 10810.3 11083.1 11110.7 11301.0 21541.6 21544.9 21555.4 21569.8 21581.7 6917.3 7103.2 7130.3 7145.7 7278.6 4247.4 4322.3 4332.1 4339.3 4340.0 3428.8 3488.6 3500.8 3527.8 3532.8 629.3 633.3 637.3 644.6 646.5 635.2 639.8 653.1 656.4 681.6 9604.4 9688.7 9754.3 9862.7 10041.5 572.0 582.2 593.1 624.7 687.8 4603.4 4929.6 4987.8 5068.6 5076.4 74750.0 75067.0 83781.6 87658.6 88659.6 65557.0 83792.0 84459.0 84840.0 86430.0 59527.7 66326.7 66521.0 67144.7 67247.0 7502.6 9392.0 9428.1 9636.7 9731.9 42982.4 45320.1 46035.0 47009.4 50431.7 63705.0 65171.7 66944.7 69698.0 69753.0 9054.5 10628.5 10719.2 10891.8 11230.7 12157.3 12184.9 12365.5 12371.3 12819.3 11718.6 11801.9 12080.6 12315.1 12339.8 11784.0 11898.5 11918.9 11987.5 11996.1 11858.4 11859.3 11866.8 11948.9 11951.0 9686.6 9994.5 10010.8 10108.8 10133.7 9580.1 9621.5 9647.7 9654.2 9658.9 09:55:50 INFO - PROCESS | 1975 | -------- Summary: end -------- 09:55:50 INFO - PROCESS | 1975 | 09:55:51 INFO - TEST-INFO | 1975: exit 0 09:55:51 INFO - TEST-OK | dromaeo_css | took 1119117ms 09:55:51 INFO - TEST-START | kraken 09:55:51 INFO - operating with platform_type : mac_ 09:55:51 INFO - Initialising browser for kraken test... 09:55:51 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp50kxoi/profile http://localhost:49669/getInfo.html) 09:55:51 INFO - PROCESS | 1979 | 1483811751761 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:55:53 INFO - PROCESS | 1979 | __metrics Screen width/height:1600/1200 09:55:53 INFO - PROCESS | 1979 | colorDepth:24 09:55:53 INFO - PROCESS | 1979 | Browser inner width/height: 1024/689 09:55:53 INFO - PROCESS | 1979 | __metrics 09:55:53 INFO - PROCESS | 1979 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:55:53 INFO - PROCESS | 1979 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:55:54 INFO - TEST-INFO | 1979: exit 0 09:55:54 INFO - Browser initialized. 09:55:54 INFO - Running cycle 1/1 for kraken test... 09:55:54 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp50kxoi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:06 INFO - PROCESS | 1980 | RSS: Main: 233803776 09:56:06 INFO - PROCESS | 1980 | 09:56:09 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection) 09:56:10 INFO - PROCESS | 1980 | RSS: Main: 288374784 09:56:10 INFO - PROCESS | 1980 | 09:56:12 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-dft) 09:56:13 INFO - PROCESS | 1980 | RSS: Main: 287711232 09:56:13 INFO - PROCESS | 1980 | 09:56:16 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-fft) 09:56:17 INFO - PROCESS | 1980 | RSS: Main: 381194240 09:56:17 INFO - PROCESS | 1980 | 09:56:19 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator) 09:56:20 INFO - PROCESS | 1980 | RSS: Main: 285310976 09:56:20 INFO - PROCESS | 1980 | 09:56:22 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:56:22 INFO - PROCESS | 1980 | RSS: Main: 287797248 09:56:22 INFO - PROCESS | 1980 | 09:56:25 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:26 INFO - PROCESS | 1980 | RSS: Main: 329756672 09:56:26 INFO - PROCESS | 1980 | 09:56:29 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:29 INFO - PROCESS | 1980 | RSS: Main: 330600448 09:56:29 INFO - PROCESS | 1980 | 09:56:32 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial) 09:56:33 INFO - PROCESS | 1980 | RSS: Main: 325603328 09:56:33 INFO - PROCESS | 1980 | 09:56:34 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:56:35 INFO - PROCESS | 1980 | RSS: Main: 303345664 09:56:35 INFO - PROCESS | 1980 | 09:56:37 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:56:37 INFO - PROCESS | 1980 | RSS: Main: 307564544 09:56:37 INFO - PROCESS | 1980 | 09:56:40 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:56:40 INFO - PROCESS | 1980 | RSS: Main: 309501952 09:56:40 INFO - PROCESS | 1980 | 09:56:43 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:56:44 INFO - PROCESS | 1980 | RSS: Main: 325541888 09:56:44 INFO - PROCESS | 1980 | 09:56:47 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:56:48 INFO - PROCESS | 1980 | RSS: Main: 319455232 09:56:48 INFO - PROCESS | 1980 | 09:56:49 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:49669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49669/tests/kraken/driver.html?testName=ai-astar) 09:56:50 INFO - PROCESS | 1980 | RSS: Main: 363786240 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | __start_tp_report 09:56:50 INFO - PROCESS | 1980 | _x_x_mozilla_page_load 09:56:50 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details 09:56:50 INFO - PROCESS | 1980 | |i|pagename|runs| 09:56:50 INFO - PROCESS | 1980 | |0;ai-astar;113;113;115;113;114;114;113;112;115;114 09:56:50 INFO - PROCESS | 1980 | |1;audio-beat-detection;143;108;107;143;109;110;108;110;130;109 09:56:50 INFO - PROCESS | 1980 | |2;audio-dft;185;181;184;187;182;183;181;184;184;185 09:56:50 INFO - PROCESS | 1980 | |3;audio-fft;82;62;79;110;84;58;58;58;75;57 09:56:50 INFO - PROCESS | 1980 | |4;audio-oscillator;89;71;88;70;69;68;85;69;68;85 09:56:50 INFO - PROCESS | 1980 | |5;imaging-gaussian-blur;89;87;90;90;90;87;90;87;90;88 09:56:50 INFO - PROCESS | 1980 | |6;imaging-darkroom;95;95;94;94;93;95;94;95;93;94 09:56:50 INFO - PROCESS | 1980 | |7;imaging-desaturate;85;85;88;85;84;85;85;87;84;83 09:56:50 INFO - PROCESS | 1980 | |8;json-parse-financial;56;55;55;55;56;55;56;55;55;56 09:56:50 INFO - PROCESS | 1980 | |9;json-stringify-tinderbox;69;74;68;77;73;70;71;71;71;73 09:56:50 INFO - PROCESS | 1980 | |10;stanford-crypto-aes;87;87;85;88;89;87;86;87;91;88 09:56:50 INFO - PROCESS | 1980 | |11;stanford-crypto-ccm;141;142;141;139;138;140;139;148;204;147 09:56:50 INFO - PROCESS | 1980 | |12;stanford-crypto-pbkdf2;174;174;182;181;217;224;232;173;178;169 09:56:50 INFO - PROCESS | 1980 | |13;stanford-crypto-sha256-iterative;67;67;70;66;66;67;67;67;68;67 09:56:50 INFO - PROCESS | 1980 | __end_tp_report 09:56:50 INFO - PROCESS | 1980 | __start_cc_report 09:56:50 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,2072 09:56:50 INFO - PROCESS | 1980 | __end_cc_report 09:56:50 INFO - PROCESS | 1980 | __startTimestamp1483811810169__endTimestamp 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | ------- Summary: start ------- 09:56:50 INFO - PROCESS | 1980 | Number of tests: 14 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:0.97 (0.8%) stddev-sans-first:1.00 09:56:50 INFO - PROCESS | 1980 | Values: 113.0 113.0 115.0 113.0 114.0 114.0 113.0 112.0 115.0 114.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:110.00 stddev:14.92 (13.6%) stddev-sans-first:12.71 09:56:50 INFO - PROCESS | 1980 | Values: 143.0 108.0 107.0 143.0 109.0 110.0 108.0 110.0 130.0 109.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.90 (1.0%) stddev-sans-first:1.94 09:56:50 INFO - PROCESS | 1980 | Values: 185.0 181.0 184.0 187.0 182.0 183.0 181.0 184.0 184.0 185.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#3] audio-fft Cycles:10 Average:72.30 Median:68.50 stddev:17.19 (25.1%) stddev-sans-first:17.87 09:56:50 INFO - PROCESS | 1980 | Values: 82.0 62.0 79.0 110.0 84.0 58.0 58.0 58.0 75.0 57.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:78.00 stddev:9.20 (11.8%) stddev-sans-first:8.51 09:56:50 INFO - PROCESS | 1980 | Values: 89.0 71.0 88.0 70.0 69.0 68.0 85.0 69.0 68.0 85.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:90.00 stddev:1.40 (1.6%) stddev-sans-first:1.48 09:56:50 INFO - PROCESS | 1980 | Values: 89.0 87.0 90.0 90.0 90.0 87.0 90.0 87.0 90.0 88.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.50 stddev:0.79 (0.8%) stddev-sans-first:0.78 09:56:50 INFO - PROCESS | 1980 | Values: 95.0 95.0 94.0 94.0 93.0 95.0 94.0 95.0 93.0 94.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.54 09:56:50 INFO - PROCESS | 1980 | Values: 85.0 85.0 88.0 85.0 84.0 85.0 85.0 87.0 84.0 83.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.52 (0.9%) stddev-sans-first:0.50 09:56:50 INFO - PROCESS | 1980 | Values: 56.0 55.0 55.0 55.0 56.0 55.0 56.0 55.0 55.0 56.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.00 stddev:2.63 (3.6%) stddev-sans-first:2.60 09:56:50 INFO - PROCESS | 1980 | Values: 69.0 74.0 68.0 77.0 73.0 70.0 71.0 71.0 71.0 73.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.50 stddev:1.65 (1.9%) stddev-sans-first:1.74 09:56:50 INFO - PROCESS | 1980 | Values: 87.0 87.0 85.0 88.0 89.0 87.0 86.0 87.0 91.0 88.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:141.50 stddev:19.99 (14.1%) stddev-sans-first:21.05 09:56:50 INFO - PROCESS | 1980 | Values: 141.0 142.0 141.0 139.0 138.0 140.0 139.0 148.0 204.0 147.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.40 Median:181.50 stddev:23.99 (13.2%) stddev-sans-first:24.70 09:56:50 INFO - PROCESS | 1980 | Values: 174.0 174.0 182.0 181.0 217.0 224.0 232.0 173.0 178.0 169.0 09:56:50 INFO - PROCESS | 1980 | 09:56:50 INFO - PROCESS | 1980 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 09:56:50 INFO - PROCESS | 1980 | Values: 67.0 67.0 70.0 66.0 66.0 67.0 67.0 67.0 68.0 67.0 09:56:50 INFO - PROCESS | 1980 | -------- Summary: end -------- 09:56:50 INFO - PROCESS | 1980 | 09:56:51 INFO - TEST-INFO | 1980: exit 0 09:56:51 INFO - TEST-OK | kraken | took 59837ms 09:56:51 INFO - SUITE-END | took 1178s 09:56:51 INFO - Completed test suite (00:19:39) 09:56:51 INFO - javascript benchmark 09:56:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.6419893811685, 3258.185453636591, 3269.9509507357407, 3302.256992176759, 3312.7184189343902, 5413.215083812855, 5512.448755124486, 5534.501894829465, 5551.972240138799, 5589.86025349367, 5471.835844924649, 5553.250311207986, 5607.0, 5643.492085712285, 5668.57485688574, 4951.133551628469, 4978.751062446863, 4994.500549945013, 5035.018171456549, 5070.797168113271, 5077.035363280965, 5169.896602067956, 5185.170372740362, 5191.065608190543, 5243.082460569404, 5039.992001599658, 5169.922451163236, 5182.119039763212, 5189.584833213334, 5207.921881171786, 5051.393832740058, 5104.668196567211, 5104.923426148611, 5108.335916330853, 5109.565686916617, 5032.47159048302, 5053.823116190915, 5078.619103567247, 5083.38999320084, 5087.618428617869, 5507.752151153168, 5522.723863806794, 5525.364583072957, 5531.751071201806, 5538.556915446795, 6069.939300607026, 6076.210092687922, 6076.513878889678, 6085.36103709113, 6096.7561297548045, 6093.238345206849, 6117.562731150785, 6121.326654068049, 6131.407250743203, 6138.1099740537375, 5613.803516876889, 5649.209110724501, 5661.207430959669, 5706.657600543981, 5713.485786279255, 6231.968840155861, 6256.624602523863, 6270.655113968775, 6272.059191121277, 6301.77943771975, 5841.474267315962, 5899.351071382144, 5974.910376344358, 5978.0, 5990.64056156632, 6114.640808386383, 6123.050927106306, 6129.0, 6129.203203583595, 6139.2325959255095, 6019.939800602026, 6031.185789918392, 6066.848328791727, 6076.149339092531, 6126.571140020245, 6155.599886007395, 6156.630602163885, 6157.353477884847, 6159.969200153971, 6165.075238714228, 4835.371401717754, 4843.321934929113, 4847.684900481458, 4865.854024379274, 4871.196252618349, 4216.262154123077, 4259.446271984684, 4263.211305908417, 4277.037666525007, 4292.849750258789, 5735.655860648291, 5743.62666426681, 5745.368009518949, 5751.338596061507, 5803.883922321615, 6184.165137706352, 6205.844853878689, 6225.564210505221, 6230.719617617218, 6241.21984751906, 5586.55307575393, 5621.550275977912, 5647.407022262685, 5716.79991200306, 5724.914126288026, 4631.536846315409, 5278.630495865329, 5318.840434786963, 5346.8395948121615, 5370.91943620846, 6256.687165641791, 6263.0, 6267.2479302484, 6274.964630835861, 6285.905711414424, 6019.759209631566, 6241.407066328691, 6246.625202487865, 6255.46828519581, 6262.0, 6248.094026366242, 6250.312465628686, 6291.811245662638, 6303.684815759286, 6310.432061114522, 6049.18336024631, 6056.152138700498, 6061.0, 6067.059605761114, 6070.6357618543025, 5983.95280825845, 6017.338092809699, 6043.033114701627, 6112.108359148444, 6129.08063790435, 5868.767558812697, 5869.266341707286, 5870.5010074144175, 5878.441548052928, 5911.172435859069, 5843.94156058451, 5851.093080572347, 5864.853378665397, 5865.237519122486, 5870.7945221917025, 5622.353429355677, 5652.7738890443925, 5687.20379146928, 5694.715264236855, 5723.284589426322, 5786.450287222707, 5827.825165245049, 5829.53363730892, 5851.297844258716, 5876.08920617305, 5673.574481913791, 5678.602497825278, 5686.744096515749, 5695.772169113189, 5702.059160238514], "value": 5581.06607666117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.97403355433494, 477.4461624515562, 483.6856043571678, 494.053886806765, 507.35565831394155, 30555.0, 39724.0, 39744.80127599362, 39759.60240397595, 39760.0, 40339.59660403395, 40766.79616601913, 40908.59091409085, 40931.18137637246, 40937.18125637485, 8549.230569248766, 8738.86891696622, 9246.306527010467, 9469.905300946972, 9486.478243696629, 11187.049100826398, 11523.942380288086, 11524.942375288154, 11584.015358694522, 11727.237729547553, 11412.429378531082, 11430.142739294544, 11519.884801152008, 11528.827067593993, 11546.942265288704, 8579.399442039059, 8780.165884240987, 11438.542458301657, 11519.94240028803, 11521.423928803526, 11227.157963152715, 11522.251053681484, 11536.596219132374, 11566.132540059445, 11566.47950842214, 32315.51526727101, 39976.200475990605, 40027.19945601072, 40098.79950600257, 40213.79893100545, 4037.0, 4038.717289789716, 4043.1913617276664, 4059.9391009134592, 4200.978995105005, 29955.850220748976, 30131.096067117804, 30235.546466803014, 30936.845315773502, 31025.37949241025, 3003.6996300370006, 3048.939021219563, 3067.9233019174476, 3152.2592190835135, 3244.707976282122, 410237.0, 459553.0, 462523.6873815609, 463953.0, 464430.677846612, 57391.71304143536, 57622.0, 57701.0, 57760.0, 57909.55226119296, 60397.0, 60427.69786151041, 60587.69706151441, 60621.4844628879, 60712.69643651842, 15344.923275383551, 15828.920855395649, 15881.841181588035, 15884.0, 15922.60193495172, 11163.553457861757, 11426.0, 12140.696482588006, 12361.825626565462, 12450.626481205578, 14639.707205856037, 15045.623859403384, 15263.694726105638, 15389.769153462707, 15414.0, 6414.486841052704, 6456.289808121103, 6478.319776423407, 6486.189226346707, 6527.738890444329, 857.339848316805, 887.6671248281895, 892.4957399069505, 917.3211823250747, 920.8848893888264, 29317.0, 29411.705882940896, 29417.11748647544, 30121.548176777367, 30158.793648254265, 1049.9580016799396, 1066.3921564708041, 1101.6034227678028, 1119.5242022140558, 1187.6080893305189, 12913.0, 15222.086674799326, 15326.770098448533, 15405.537833865003, 15576.688466230611, 595524.0447595469, 598656.0, 598934.0, 599213.0, 601469.0, 58731.0, 59086.40913590895, 59106.817863642485, 59240.0, 59290.0, 1422.879055280292, 1458.3874772595536, 1477.475496198877, 1480.9333579988927, 1485.9925700371646, 24290.51418971646, 27401.0, 28575.571366429103, 29265.561016584343, 29358.0, 45471.77264113658, 48525.757371212916, 48617.51382486271, 48639.02721945612, 48748.0, 501139.0, 502493.0, 503086.0, 503539.0, 503768.0, 37738.0, 38122.42816357702, 38427.615723842406, 38500.42249366206, 38524.11069169767, 44959.32561011522, 45367.7731611353, 45665.40171094129, 46262.53737462583, 46592.30111548397, 31965.36069278648, 36864.26271474609, 37292.254154916205, 37297.440538391405, 38185.6181438182, 30426.69573304327, 36900.630993689716, 37517.81241093777, 38533.807330963165, 38846.6115338843, 34667.653323466446, 35312.0, 36775.0, 37580.436293456165, 37750.0, 26017.6097358536, 30941.7286937574, 31163.688363116078, 31344.843275783474, 33426.49860252049, 22748.545029099656, 23863.522729544966, 24176.0, 24515.75484245206, 24612.261632151065], "value": 19749.846400605573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.4109002326208, 381.2280132731221, 387.4188716924613, 394.57583098169476, 417.7159531518568, 15339.53981380559, 15376.846231537682, 18578.349757758482, 20575.27986520472, 20729.06719197636, 21005.36983890482, 21012.94935253239, 21316.0, 21450.0, 21608.675869861963, 5689.6017278790505, 5840.386759390267, 5951.285845698522, 6101.328853826098, 6115.4190351916495, 5890.676012819294, 5913.9112913306335, 5924.2298501195, 5932.644041357511, 5940.405959404068, 3521.6478352164836, 3867.4005529142887, 4037.2934736421157, 4064.5732198119217, 6477.417032467077, 6546.509011824109, 6563.93436065638, 6564.179477565304, 6573.112629795012, 6576.309487503839, 5619.859503512404, 5752.482276595085, 5804.2744656917885, 5808.680522571257, 5813.069908814569, 13124.0, 13291.800622990662, 14399.640008999753, 24379.512409751882, 24772.5045499089, 2540.9237722868343, 2980.8658610362586, 2995.6405231372373, 3001.0, 3056.2206637307568, 9998.850017249675, 10364.0, 10403.375797452101, 13312.400941957538, 13974.720505589932, 1762.8765986380959, 1800.19891148439, 1816.3551939061574, 1827.0590645817415, 1839.9356022539148, 96228.51885740597, 130578.0, 131515.0, 131783.02325465222, 131811.0, 9693.321467497208, 9945.403275803475, 10319.793604127875, 10358.741031474123, 10425.530851111718, 10800.891991080145, 12584.874151258553, 12682.302473364054, 12703.30131842739, 12705.950571862686, 7893.644785984529, 8093.635786389626, 8112.959435202786, 8193.754187374388, 8237.46456480327, 8300.335973122135, 8311.459755115899, 8328.125546817497, 8452.366072544584, 8486.660533578588, 4007.0783719744622, 4008.7594744315434, 4009.278329900588, 4029.838806447768, 4075.144219713893, 1693.8729595280404, 1958.9911195734207, 1999.780024197337, 2002.7696814866476, 2003.4390370696228, 386.29693956998557, 408.8814243869304, 421.9894502637459, 423.1177993882789, 1027.2860362048305, 11793.056555475541, 11967.760644787055, 12054.09594280415, 12119.939400302943, 12201.938990304992, 358.75246080204573, 447.7112262590611, 448.5447271019917, 454.0, 455.2738382280258, 5977.342492325841, 6004.309504406961, 6058.363871793486, 6150.815475535741, 6205.968970155211, 605601.0, 712570.0, 726211.0, 735354.0504329398, 736843.0, 115381.0, 127176.72823271649, 146614.0, 148523.05907763785, 154301.0, 1067.6423398161703, 1103.332483847277, 1124.6344937895217, 1129.7119234595125, 1135.6138912769534, 18745.156467958703, 19556.82659040462, 19559.119839607538, 19604.607907841477, 19663.8033619662, 56481.71759141178, 57010.0, 57980.0, 58910.3490342927, 58926.0, 78037.0, 83636.58181709053, 84755.0, 85113.0, 86085.13914860772, 6892.379685828299, 7288.453365997465, 7372.815679608053, 7483.738069167553, 7749.302562769378, 10711.410872402093, 10735.355878647306, 12758.90390502494, 12907.096503244682, 13009.21944683322, 10746.83879741789, 10837.783244335227, 11196.720081998015, 11294.435278235891, 11297.43512824372, 10825.512851921525, 10865.782684346426, 11064.944675276573, 11242.032644287054, 11281.32312061279, 11131.610393636183, 11168.553257869919, 11605.825912611148, 11648.242864213798, 11679.124065695276, 7299.343059124704, 7365.410767138509, 7374.705011799468, 7518.586477743776, 7563.848723025619, 9832.459214742972, 10945.781084378426, 11315.660530184108, 11334.433278336215, 11344.489497972776], "value": 8877.995636026331, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.6536913825248, 1473.9705205895875, 1491.2022068193517, 1500.699860027994, 1506.47273454291, 7040.7183712651495, 8446.49321040738, 8467.872981905268, 8469.618867150977, 8471.110533393981, 7414.555126692402, 7681.615919204031, 7704.88442673359, 8140.104588495261, 8375.20435558621, 2301.976980230201, 2338.6141286687675, 2339.8596084234914, 2341.0518739910362, 2346.5424242272766, 2261.0955617752948, 2292.426893276681, 2295.0704964489323, 2332.9416764580938, 2352.6588644646517, 2296.357020034393, 2296.391456264083, 2323.9883800580974, 2326.325365643961, 2327.1622216002224, 2280.942976425594, 2293.7820907013806, 2294.3461113582634, 2316.1661801751357, 2327.755585663515, 2272.204728345066, 2307.261676263596, 2308.0883051194683, 2314.0396735354975, 2323.1520495019367, 8364.456310339809, 8433.704820331259, 8440.57797110142, 8441.451305665112, 8449.112843151443, 4952.826651067231, 5136.845894623168, 5146.511081447256, 5147.22791581262, 5148.227765835118, 3273.885414010498, 3402.8638854445794, 3509.543759311299, 3546.8935931922083, 3550.414181660023, 1190.8451901252868, 1200.8498937632796, 1237.560665963579, 1252.4364036183663, 1270.0855384123417, 9023.323250756219, 10744.838827417518, 10789.730256743565, 10790.5683772649, 10882.40146791926, 4349.934750978738, 4482.305242687388, 4556.863294101182, 4678.111158879802, 4684.531546845356, 9389.295802814817, 9395.107464790834, 9396.295277854051, 9407.858882116774, 9411.482368469662, 5126.333576635014, 5126.410462796826, 5174.223866420067, 5203.791848326025, 5211.921821172686, 4255.085156691264, 4392.846250381222, 4424.469063712375, 4700.976495117503, 4747.216709243005, 3712.1833196696684, 3752.418375151856, 3847.1920896611464, 3856.1709232515145, 3898.5321761388814, 1264.6395777203452, 1265.1966001589058, 1266.0631132961726, 1283.8587755346905, 1289.232906420678, 806.2542148512603, 836.8284501677142, 843.308487040633, 876.7900297589285, 884.0717246890739, 4628.912205631674, 4690.64820138491, 4720.12677654635, 4735.431748190171, 4808.855734327975, 562.2634349002738, 960.9957594313959, 997.2670087485692, 1001.3191030099457, 1032.2774058159318, 3563.893083207508, 3669.8348574313686, 3714.270011541515, 3758.830852611639, 3804.7907365094634, 7780.610969451504, 9755.268354873271, 9857.457839818879, 9859.161971232383, 9876.80246395068, 9808.754781130387, 9814.656487023063, 9817.214622830157, 9835.704928852147, 9857.309988300893, 7680.231976802387, 7750.7674769756995, 7762.2237776223055, 7843.686112596798, 7856.450048496665, 5163.870903227449, 5432.891342173055, 5460.945390546202, 5465.672059676433, 5528.341497550799, 9026.638934442548, 9049.683261085831, 9100.180983711232, 9114.726558203265, 9200.861987070333, 8980.191782739585, 9218.400803947721, 9252.444853308823, 9327.300452466228, 9373.81252374935, 6657.7669781557415, 6872.62200578973, 7168.0, 7309.8903516446235, 7509.098908131059, 7848.9215107848195, 7943.523388596703, 8036.718714844952, 8062.234087761653, 8063.153368896416, 6975.337342952412, 6995.335443132894, 7061.470389720689, 7110.288971102848, 7260.128784545888, 6905.136857892763, 6954.860902782018, 6977.895331569929, 7092.787216383516, 7120.145582530129, 6301.275353334425, 6334.3665633436285, 6405.263394709482, 6407.2631647359285, 6410.0705397718, 4888.877778055577, 4914.950850491449, 4942.5304596063315, 4963.255511673278, 5047.64666473358, 4603.769811509478, 4897.436794768647, 4941.308216849716, 4997.750112494434, 5015.774290156879], "value": 4368.701901578922, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.08542989223395, 347.2638007424261, 354.19950910941276, 368.7934756536342, 370.62196559509283, 4543.954560454395, 4624.653151013671, 5171.767270472828, 5185.896282074356, 5210.452902445246, 4629.574115154786, 4670.944784294917, 4869.488703686106, 4935.284383764352, 5129.615003948931, 3859.9035024124482, 4049.02823322402, 4051.392291156321, 4062.939055914164, 4076.490438695163, 3607.6572725591027, 3969.5435024972066, 4000.219957108365, 4070.931690045897, 4072.3077076896852, 3439.914002149954, 3832.7508711933633, 4010.6189911958318, 4051.7771522566245, 4108.979455102721, 3609.819509024538, 4000.739951903146, 4028.798560071985, 4051.5543290238197, 4063.1264278180183, 3186.2193762528095, 3425.6231814500384, 3429.9828500857584, 3619.7647152934974, 3620.6379362063844, 4792.520747925215, 5045.394552653705, 5052.9242061369105, 5061.670991385548, 5067.746612669352, 4184.665226781881, 4407.60331570157, 4667.432911898442, 4672.0, 4703.576678098953, 4145.0, 4416.403785488982, 5420.512153906167, 5420.7018613976215, 5420.945790542083, 3969.444277801081, 3969.503812023497, 3971.663802405964, 4005.939910901339, 4020.396940458925, 4576.565226303496, 4836.975815120937, 5403.729813509309, 5633.943660563383, 5663.603547751659, 3623.9818800905828, 3925.9203718976937, 4061.2080644274083, 4073.6130067643526, 4073.6944729145434, 3506.649335066524, 3810.123671555558, 3923.156521347924, 4134.64855487281, 4142.668586513072, 4339.349097635317, 4379.401518445744, 4451.287793953017, 4673.369095172108, 4729.385179926588, 4654.371659825948, 5182.922256166161, 5436.782528698887, 5448.291722076184, 5467.917981230285, 1055.3140458701753, 1289.6711338608595, 1316.2957817567747, 1328.1074468881804, 1329.415057374752, 897.869808877709, 911.7128104647015, 939.910708482693, 964.9482064549675, 971.2424309038961, 4256.34026725858, 4705.364775755298, 4751.524847515221, 4754.500777418321, 4825.252085926617, 4326.848560300375, 4696.577308042224, 5229.529342359206, 5248.685078895279, 5280.788768449296, 4210.221109094828, 4429.335599660077, 4455.888602784891, 4716.834910778106, 4796.736179510091, 2389.411041657313, 2491.7383674714256, 2741.999170302825, 2744.423671029106, 2770.337797321591, 4387.802548885308, 4499.887502812456, 5038.84883453497, 5224.242484839677, 5441.421987623624, 5010.0, 5018.787975695558, 5156.845294641167, 5398.568114550826, 5428.37573679032, 4583.335416364608, 4712.269598212282, 5289.96845615109, 5438.728063596725, 5439.259436980207, 4487.596116349544, 4614.077184563033, 5104.3619547556555, 5165.638405311592, 5208.401033881152, 4736.526347365236, 5152.536271735412, 5158.767855446439, 5190.247414125006, 5202.947970520247, 4904.485029072039, 5218.243354713622, 5334.679414454725, 5408.972955135199, 5440.836774896759, 3278.09852290622, 3455.1016735649414, 3470.35798377301, 3715.6841668458483, 3810.5236845394325, 4981.825636102719, 5037.798488060436, 5173.32746742921, 5226.529612334908, 5564.888702226014, 4867.931129270117, 5191.0, 5409.972950135224, 5415.323084614423, 5428.647137936022, 5188.948110518846, 5410.59420543453, 5410.943841340423, 5416.702081385404, 5478.8904221914545, 5035.827428719883, 5259.895421961431, 5314.867128321823, 5331.4805280494875, 5338.46615338463, 4597.540245975376, 5200.245964335075, 5275.920861187162, 5306.761195746123, 5360.276362690986, 4881.926771098508, 5287.836675931289, 5295.655782374133, 5312.62811603184, 5338.279332290246], "value": 3931.7927851346844, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.0791494801603, 759.0587671287598, 781.1173374087281, 796.1839114907219, 847.4788005376695, 14421.69252919533, 15143.62140946477, 15196.240187990585, 15211.391544338227, 15215.315310811013, 11953.820692689595, 14319.0, 14489.203093829836, 14518.927405362985, 14526.709465810678, 662.0896267631994, 683.9829004274883, 688.215434404779, 854.8845905802731, 879.7800549862534, 867.9001914779786, 873.8558137907238, 885.663447889801, 886.5921676029035, 888.3914518554778, 866.7746385939638, 901.3555307954811, 943.2642538819697, 947.6304241345881, 952.1668540027476, 673.737242475435, 697.4072038767058, 936.148105224246, 945.7682867697424, 946.068122898944, 547.4334064243528, 663.9933600663979, 672.6872004517874, 689.8034060292792, 691.8512519808215, 14226.0, 14382.92808535961, 14441.133531988115, 14451.855481445156, 14477.0, 13696.863031369658, 13744.518941836988, 13797.862021379759, 13803.0, 13823.51617693386, 10327.431991240479, 12223.205491643104, 12258.877411225863, 12270.631881043493, 12526.561570345086, 4235.369382787618, 4247.554006829269, 4296.226679197744, 4379.868603941887, 4402.977985110086, 72142.0, 100357.49821250846, 101499.49250253702, 102674.48662756638, 103207.4839625797, 10507.159427245802, 10810.29733067348, 11083.113350931906, 11110.722231944266, 11301.0, 21541.569168616854, 21544.922753862244, 21555.353339399844, 21569.78430215678, 21581.67627485589, 6917.342852429011, 7103.183133939559, 7130.322619351154, 7145.714171433188, 7278.599677017709, 4247.3628955656895, 4322.265214913409, 4332.090261045216, 4339.327404252346, 4340.045190058182, 3428.7599868009497, 3488.633693462201, 3500.824958752052, 3527.8236088195486, 3532.8410221540094, 629.288903538998, 633.2622494793513, 637.2544123375661, 644.5552568727563, 646.4537465841356, 635.1911766891841, 639.834161363787, 653.1052458132389, 656.4321861589691, 681.6216999565289, 9604.375715578464, 9688.70933871985, 9754.268429867789, 9862.65480708186, 10041.548130334006, 571.9571032172604, 582.1645938078871, 593.1340243244824, 624.7032659486798, 687.7730348984823, 4603.355530225771, 4929.630277729185, 4987.775550100254, 5068.6451948364, 5076.416212135578, 74750.0, 75067.0, 83781.58109209414, 87658.56170719233, 88659.55670221608, 65557.0, 83792.0, 84459.0, 84840.0, 86430.0, 59527.70236148878, 66326.66836665882, 66521.0, 67144.66427667928, 67247.0, 7502.587357695161, 9392.0, 9428.057194280516, 9636.71089867305, 9731.854022189531, 42982.35526467043, 45320.093598128515, 46035.0, 47009.3546725863, 50431.747841260556, 63705.0, 65171.67414163089, 66944.6652766733, 69697.9545306831, 69753.0, 9054.456732596065, 10628.52171652293, 10719.249652524248, 10891.836622450512, 11230.66308010761, 12157.331346776013, 12184.939075304566, 12365.505379784709, 12371.319577423328, 12819.294938778457, 11718.589849355232, 11801.940990294994, 12080.577179798665, 12315.07636927252, 12339.75320493603, 11784.0, 11898.524059037887, 11918.940405298265, 11987.460564274454, 11996.100292478273, 11858.407079646156, 11859.34773587461, 11866.82199766987, 11948.880511194777, 11951.043916486835, 9686.612535498783, 9994.500274986367, 10010.8498372523, 10108.797824043331, 10133.746656333651, 9580.13778759915, 9621.51892405363, 9647.71056868295, 9654.179394751243, 9658.9034109658], "value": 7102.938150222199, "unit": "score"}], "name": "dromaeo_css", "value": 7017.313477028619, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 113.0, 114.0, 114.0, 113.0, 112.0, 115.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 107.0, 143.0, 109.0, 110.0, 108.0, 110.0, 130.0, 109.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 184.0, 187.0, 182.0, 183.0, 181.0, 184.0, 184.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 79.0, 110.0, 84.0, 58.0, 58.0, 58.0, 75.0, 57.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 88.0, 70.0, 69.0, 68.0, 85.0, 69.0, 68.0, 85.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 90.0, 90.0, 87.0, 90.0, 87.0, 90.0, 88.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 93.0, 95.0, 94.0, 95.0, 93.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 88.0, 85.0, 84.0, 85.0, 85.0, 87.0, 84.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 55.0, 56.0, 55.0, 56.0, 55.0, 55.0, 56.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 68.0, 77.0, 73.0, 70.0, 71.0, 71.0, 71.0, 73.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 88.0, 89.0, 87.0, 86.0, 87.0, 91.0, 88.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 141.0, 139.0, 138.0, 140.0, 139.0, 148.0, 204.0, 147.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 182.0, 181.0, 217.0, 224.0, 232.0, 173.0, 178.0, 169.0], "value": 190.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 70.0, 66.0, 66.0, 67.0, 67.0, 67.0, 68.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1451.6000000000001, "alertThreshold": 2.0}]} 09:56:51 INFO - Return code: 0 09:56:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:56:51 INFO - # TBPL SUCCESS # 09:56:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:51 INFO - 2017-01-07 09:56:51.499 screenresolution[1984:16991] starting screenresolution argv=screenresolution get 09:56:51 INFO - 2017-01-07 09:56:51.503 screenresolution[1984:16991] Display 0: 1600x1200x32@60 09:56:51 INFO - 2017-01-07 09:56:51.509 screenresolution[1985:16994] starting screenresolution argv=screenresolution list 09:56:51 INFO - Available Modes on Display 0 09:56:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:51 INFO - Intel Iris: 09:56:51 INFO - Chipset Model: Intel Iris 09:56:51 INFO - Type: GPU 09:56:51 INFO - Bus: Built-In 09:56:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:51 INFO - Vendor: Intel (0x8086) 09:56:51 INFO - Device ID: 0x0a2e 09:56:51 INFO - Revision ID: 0x0009 09:56:51 INFO - Displays: 09:56:51 INFO - Display: 09:56:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:51 INFO - Display Serial Number: 1600x1200 60 09:56:51 INFO - Main Display: Yes 09:56:51 INFO - Mirror: Off 09:56:51 INFO - Online: Yes 09:56:51 INFO - Rotation: Supported 09:56:51 INFO - Return code: 0 09:56:51 INFO - Running post-action listener: _resource_record_post_action 09:56:51 INFO - [mozharness: 2017-01-07 17:56:51.744683Z] Finished run-tests step (success) 09:56:51 INFO - Running post-run listener: _resource_record_post_run 09:56:51 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 352256; Write bytes: 551700480; Read time: 4; Write time: 1829 09:56:51 INFO - TinderboxPrint: CPU usage
53.7% 09:56:51 INFO - TinderboxPrint: I/O read bytes / time
352,256 / 4 09:56:51 INFO - TinderboxPrint: I/O write bytes / time
551,700,480 / 1,829 09:56:51 INFO - TinderboxPrint: CPU idle
2,224.7 (46.3%) 09:56:51 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 09:56:51 INFO - TinderboxPrint: CPU user
1,624.7 (33.8%) 09:56:51 INFO - TinderboxPrint: Swap in / out
249,581,568 / 0 09:56:51 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245707264; Write bytes: 238700032; Read time: 17948; Write time: 478 09:56:51 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 352256; Write bytes: 297509376; Read time: 4; Write time: 1312 09:56:52 INFO - Running post-run listener: _upload_blobber_files 09:56:52 INFO - Blob upload gear active. 09:56:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:56:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:56:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:56:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:56:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:56:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:56:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:53 INFO - (blobuploader) - INFO - Done attempting. 09:56:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:56:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:56:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:54 INFO - (blobuploader) - INFO - Done attempting. 09:56:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:56:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:56:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:55 INFO - (blobuploader) - INFO - Done attempting. 09:56:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:57 INFO - (blobuploader) - INFO - Done attempting. 09:56:57 INFO - (blobuploader) - INFO - Iteration through files over. 09:56:57 INFO - Return code: 0 09:56:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:56:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:56:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e98ab2daed845052eabb25108d3aef34fbfbc7ee841d141c767140a6f7f7a96b7a07a671b3f6a8e6b593ec95e9f04d060926496e63534387518a9f97b74c5268", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6ba8767647ee40e54adfa9bcc1f84cd4d8bc329c8164b76d1454169f6d56b349b28cee99462a3bac0a53ec745a77626cbf513beab98740640e260a2605a65f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f0840c0ec6bb6a412a8405839cc3693eb3e89118655a009281fc27b5acdfa7b6caf7dc3354677fef8db91bff5a640dd1a5bb75c67de87cef89453a0accfc588", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27a0867fc136762957e9509770ab18fc28df3bfa847ce077c67696c94b823864b0d746a72db56798e834e7fb9b40c0e6045ff75969819d5cde80fab286fc4128"} 09:56:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:56:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:56:57 INFO - Contents: 09:56:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e98ab2daed845052eabb25108d3aef34fbfbc7ee841d141c767140a6f7f7a96b7a07a671b3f6a8e6b593ec95e9f04d060926496e63534387518a9f97b74c5268", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6ba8767647ee40e54adfa9bcc1f84cd4d8bc329c8164b76d1454169f6d56b349b28cee99462a3bac0a53ec745a77626cbf513beab98740640e260a2605a65f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f0840c0ec6bb6a412a8405839cc3693eb3e89118655a009281fc27b5acdfa7b6caf7dc3354677fef8db91bff5a640dd1a5bb75c67de87cef89453a0accfc588", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27a0867fc136762957e9509770ab18fc28df3bfa847ce077c67696c94b823864b0d746a72db56798e834e7fb9b40c0e6045ff75969819d5cde80fab286fc4128"} 09:56:57 INFO - Running post-run listener: copy_logs_to_upload_dir 09:56:57 INFO - Copying logs to upload dir... 09:56:57 INFO - mkdir: /builds/slave/test/build/upload/logs 09:56:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.071281 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-07 09:56:57.676726) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:56:57.681354) ========= 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.sQHKQtTboK/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.it3FrhUaZH/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/e98ab2daed845052eabb25108d3aef34fbfbc7ee841d141c767140a6f7f7a96b7a07a671b3f6a8e6b593ec95e9f04d060926496e63534387518a9f97b74c5268", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6ba8767647ee40e54adfa9bcc1f84cd4d8bc329c8164b76d1454169f6d56b349b28cee99462a3bac0a53ec745a77626cbf513beab98740640e260a2605a65f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f0840c0ec6bb6a412a8405839cc3693eb3e89118655a009281fc27b5acdfa7b6caf7dc3354677fef8db91bff5a640dd1a5bb75c67de87cef89453a0accfc588", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27a0867fc136762957e9509770ab18fc28df3bfa847ce077c67696c94b823864b0d746a72db56798e834e7fb9b40c0e6045ff75969819d5cde80fab286fc4128"} build_url:https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/HNAJThpEQPO3CQuDCXlhOA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e98ab2daed845052eabb25108d3aef34fbfbc7ee841d141c767140a6f7f7a96b7a07a671b3f6a8e6b593ec95e9f04d060926496e63534387518a9f97b74c5268", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6ba8767647ee40e54adfa9bcc1f84cd4d8bc329c8164b76d1454169f6d56b349b28cee99462a3bac0a53ec745a77626cbf513beab98740640e260a2605a65f5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f0840c0ec6bb6a412a8405839cc3693eb3e89118655a009281fc27b5acdfa7b6caf7dc3354677fef8db91bff5a640dd1a5bb75c67de87cef89453a0accfc588", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27a0867fc136762957e9509770ab18fc28df3bfa847ce077c67696c94b823864b0d746a72db56798e834e7fb9b40c0e6045ff75969819d5cde80fab286fc4128"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:56:57.719764) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:56:57.720116) ========= 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.sQHKQtTboK/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.it3FrhUaZH/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005198 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:56:57.774816) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:56:57.778196) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:56:57.778510) ========= ========= Total master_lag: 0.22 =========