builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0209 starttime: 1485954817.65 results: success (0) buildid: 20170201041209 builduid: 9a9f81e060aa44ad99293ddcddb29c72 revision: ae91b2b5bb69ffd18b4679188730d0c6af3b4a95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:37.655347) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:37.655934) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:37.656213) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:37.834140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:37.834727) ========= 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.wAQfVCPO7h/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.LpWDYo9B63/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-01 05:13:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.8M=0s 2017-02-01 05:13:38 (27.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.499876 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:38.378586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:38.378960) ========= 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.wAQfVCPO7h/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.LpWDYo9B63/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.024893 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:38.477098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 05:13:38.477688) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ae91b2b5bb69ffd18b4679188730d0c6af3b4a95 --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 ae91b2b5bb69ffd18b4679188730d0c6af3b4a95 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wAQfVCPO7h/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.LpWDYo9B63/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-01 05:13:38,598 truncating revision to first 12 chars 2017-02-01 05:13:38,598 Setting DEBUG logging. 2017-02-01 05:13:38,598 attempt 1/10 2017-02-01 05:13:38,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ae91b2b5bb69?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 05:13:39,433 unpacking tar archive at: mozilla-central-ae91b2b5bb69/testing/mozharness/ program finished with exit code 0 elapsedTime=1.109068 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-01 05:13:39.637449) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:39.637754) ========= script_repo_revision: ae91b2b5bb69ffd18b4679188730d0c6af3b4a95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:39.638165) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:39.638428) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 05:13:39.795526) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-01 05:13:39.795930) ========= /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.wAQfVCPO7h/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.LpWDYo9B63/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:13:40 INFO - MultiFileLogger online at 20170201 05:13:40 in /builds/slave/test 05:13:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:13:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:13:40 INFO - {'append_to_log': False, 05:13:40 INFO - 'base_work_dir': '/builds/slave/test', 05:13:40 INFO - 'blob_upload_branch': 'Firefox', 05:13:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:13:40 INFO - 'branch': 'Firefox', 05:13:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:13:40 INFO - 'config_files': ('talos/mac_config.py',), 05:13:40 INFO - 'default_actions': ('clobber', 05:13:40 INFO - 'read-buildbot-config', 05:13:40 INFO - 'download-and-extract', 05:13:40 INFO - 'populate-webroot', 05:13:40 INFO - 'create-virtualenv', 05:13:40 INFO - 'install', 05:13:40 INFO - 'run-tests'), 05:13:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:13:40 INFO - 'download_minidump_stackwalk': True, 05:13:40 INFO - 'download_symbols': 'ondemand', 05:13:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:13:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:13:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:13:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:13:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:13:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:13:40 INFO - 'installer_path': 'installer.exe', 05:13:40 INFO - 'log_level': 'info', 05:13:40 INFO - 'log_name': 'talos', 05:13:40 INFO - 'log_to_console': True, 05:13:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:13:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:13:40 INFO - 'opt_config_files': (), 05:13:40 INFO - 'pip_index': False, 05:13:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:40 INFO - 'cmd': ('bash', 05:13:40 INFO - '-c', 05:13:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:40 INFO - 'enabled': True, 05:13:40 INFO - 'halt_on_failure': False, 05:13:40 INFO - 'name': 'check_screen_resolution'},), 05:13:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:13:40 INFO - 'cmd': ('bash', 05:13:40 INFO - '-c', 05:13:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:13:40 INFO - 'enabled': True, 05:13:40 INFO - 'halt_on_failure': False, 05:13:40 INFO - 'name': 'check_screen_resolution'},), 05:13:40 INFO - 'run_cmd_checks_enabled': True, 05:13:40 INFO - 'sps_profile': False, 05:13:40 INFO - 'sps_profile_interval': 0, 05:13:40 INFO - 'suite': 'dromaeojs', 05:13:40 INFO - 'system_bits': '32', 05:13:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:13:40 INFO - 'title': 't-yosemite-r7-0209', 05:13:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:13:40 INFO - 'use_talos_json': True, 05:13:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:13:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:13:40 INFO - 'work_dir': 'build'} 05:13:40 INFO - [mozharness: 2017-02-01 13:13:40.026612Z] Running clobber step. 05:13:40 INFO - Running pre-action listener: _resource_record_pre_action 05:13:40 INFO - Running main action method: clobber 05:13:40 INFO - rmtree: /builds/slave/test/build 05:13:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:13:42 INFO - Running post-action listener: _resource_record_post_action 05:13:42 INFO - [mozharness: 2017-02-01 13:13:42.904078Z] Finished clobber step (success) 05:13:42 INFO - [mozharness: 2017-02-01 13:13:42.904217Z] Running read-buildbot-config step. 05:13:42 INFO - Running pre-action listener: _resource_record_pre_action 05:13:42 INFO - Running main action method: read_buildbot_config 05:13:42 INFO - Using buildbot properties: 05:13:42 INFO - { 05:13:42 INFO - "project": "", 05:13:42 INFO - "product": "firefox", 05:13:42 INFO - "script_repo_revision": "production", 05:13:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:13:42 INFO - "repository": "", 05:13:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:13:42 INFO - "buildid": "20170201041209", 05:13:42 INFO - "pgo_build": "False", 05:13:42 INFO - "basedir": "/builds/slave/test", 05:13:42 INFO - "buildnumber": 50, 05:13:42 INFO - "slavename": "t-yosemite-r7-0209", 05:13:42 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:13:42 INFO - "platform": "yosemite_r7", 05:13:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:13:42 INFO - "branch": "mozilla-central", 05:13:42 INFO - "repo_path": "mozilla-central", 05:13:42 INFO - "revision": "ae91b2b5bb69ffd18b4679188730d0c6af3b4a95", 05:13:42 INFO - "stage_platform": "macosx64", 05:13:42 INFO - "builduid": "9a9f81e060aa44ad99293ddcddb29c72", 05:13:42 INFO - "slavebuilddir": "test" 05:13:42 INFO - } 05:13:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 05:13:42 INFO - Running post-action listener: _resource_record_post_action 05:13:42 INFO - [mozharness: 2017-02-01 13:13:42.907544Z] Finished read-buildbot-config step (success) 05:13:42 INFO - [mozharness: 2017-02-01 13:13:42.907698Z] Running download-and-extract step. 05:13:42 INFO - Running pre-action listener: _resource_record_pre_action 05:13:42 INFO - Running main action method: download_and_extract 05:13:42 INFO - mkdir: /builds/slave/test/build/tests 05:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:42 INFO - trying https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 05:13:42 INFO - Downloading https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 05:13:44 INFO - Downloaded 1199 bytes. 05:13:44 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 05:13:44 INFO - Using the following test package requirements: 05:13:44 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:13:44 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 05:13:44 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 05:13:44 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'jsshell-mac.zip'], 05:13:44 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 05:13:44 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 05:13:44 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 05:13:44 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 05:13:44 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 05:13:44 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 05:13:44 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 05:13:44 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:13:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:13:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:44 INFO - Fetch https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:13:45 INFO - Expected file size: 16373261 05:13:45 INFO - Obtained file size: 16373261 05:13:51 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:13:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 05:13:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:13:51 INFO - Fetch https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 05:13:52 INFO - Expected file size: 16373261 05:13:52 INFO - Obtained file size: 16373261 05:13:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 05:13:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:13:58 INFO - Fetch https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 05:14:00 INFO - Expected file size: 14403454 05:14:00 INFO - Obtained file size: 14403454 05:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:00 INFO - trying https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:14:00 INFO - Downloading https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 05:14:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 05:14:02 INFO - Downloaded 70196925 bytes. 05:14:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:14:02 INFO - mkdir: /builds/slave/test/properties 05:14:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:14:02 INFO - Writing to file /builds/slave/test/properties/build_url 05:14:02 INFO - Contents: 05:14:02 INFO - build_url:https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 05:14:03 INFO - Running post-action listener: _resource_record_post_action 05:14:03 INFO - Running post-action listener: set_extra_try_arguments 05:14:03 INFO - [mozharness: 2017-02-01 13:14:03.425332Z] Finished download-and-extract step (success) 05:14:03 INFO - [mozharness: 2017-02-01 13:14:03.425492Z] Running populate-webroot step. 05:14:03 INFO - Running pre-action listener: _resource_record_pre_action 05:14:03 INFO - Running main action method: populate_webroot 05:14:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:14:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:14:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:14:03 INFO - '--tppagecycles', 05:14:03 INFO - '7'], 05:14:03 INFO - 'tests': ['tsvgm']}}, 05:14:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:14:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:14:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:14:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:14:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['damp', 'tps']}, 05:14:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'tests': ['damp', 'tps']}, 05:14:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['dromaeo_dom']}, 05:14:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:14:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['basic_compositor_video']}, 05:14:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:14:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['a11yr', 05:14:03 INFO - 'ts_paint', 05:14:03 INFO - 'tpaint', 05:14:03 INFO - 'sessionrestore', 05:14:03 INFO - 'sessionrestore_no_auto_restore', 05:14:03 INFO - 'tabpaint']}, 05:14:03 INFO - 'other-e10s': {'tests': ['a11yr', 05:14:03 INFO - 'ts_paint', 05:14:03 INFO - 'tpaint', 05:14:03 INFO - 'sessionrestore', 05:14:03 INFO - 'sessionrestore_no_auto_restore', 05:14:03 INFO - 'tabpaint']}, 05:14:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:14:03 INFO - 'ts_paint', 05:14:03 INFO - 'tpaint', 05:14:03 INFO - 'sessionrestore', 05:14:03 INFO - 'sessionrestore_no_auto_restore', 05:14:03 INFO - 'tabpaint']}, 05:14:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:14:03 INFO - 'ts_paint', 05:14:03 INFO - 'tpaint', 05:14:03 INFO - 'sessionrestore', 05:14:03 INFO - 'sessionrestore_no_auto_restore', 05:14:03 INFO - 'tabpaint']}, 05:14:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['a11yr', 05:14:03 INFO - 'ts_paint', 05:14:03 INFO - 'tpaint', 05:14:03 INFO - 'sessionrestore', 05:14:03 INFO - 'sessionrestore_no_auto_restore', 05:14:03 INFO - 'tabpaint']}, 05:14:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['a11yr', 05:14:03 INFO - 'ts_paint', 05:14:03 INFO - 'tpaint', 05:14:03 INFO - 'sessionrestore', 05:14:03 INFO - 'sessionrestore_no_auto_restore', 05:14:03 INFO - 'tabpaint']}, 05:14:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['tsvgx', 05:14:03 INFO - 'tsvgr_opacity', 05:14:03 INFO - 'tart', 05:14:03 INFO - 'tscrollx', 05:14:03 INFO - 'cart', 05:14:03 INFO - 'tsvg_static']}, 05:14:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:14:03 INFO - 'tsvgr_opacity', 05:14:03 INFO - 'tart', 05:14:03 INFO - 'tscrollx', 05:14:03 INFO - 'cart', 05:14:03 INFO - 'tsvg_static']}, 05:14:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'talos_options': ['--disable-e10s'], 05:14:03 INFO - 'tests': ['tp5o']}, 05:14:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:14:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'talos_options': ['--disable-e10s', 05:14:03 INFO - '--xperf_path', 05:14:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:03 INFO - 'tests': ['tp5n']}, 05:14:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:14:03 INFO - 'talos_options': ['--xperf_path', 05:14:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:03 INFO - 'tests': ['tp5n']}}, 05:14:03 INFO - 'talos.zip': {'path': '', 05:14:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:14:03 INFO - Running post-action listener: _resource_record_post_action 05:14:03 INFO - [mozharness: 2017-02-01 13:14:03.444224Z] Finished populate-webroot step (success) 05:14:03 INFO - [mozharness: 2017-02-01 13:14:03.444337Z] Running create-virtualenv step. 05:14:03 INFO - Running pre-action listener: _resource_record_pre_action 05:14:03 INFO - Running main action method: create_virtualenv 05:14:03 INFO - Creating virtualenv /builds/slave/test/build/venv 05:14:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:14:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:14:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:14:03 INFO - Using real prefix '/tools/python27' 05:14:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:14:04 INFO - Installing distribute.............................................................................................................................................................................................done. 05:14:07 INFO - Installing pip.................done. 05:14:07 INFO - Return code: 0 05:14:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:14:07 INFO - Reading from file tmpfile_stdout 05:14:07 INFO - Output received: 05:14:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:14:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:07 INFO - 'HOME': '/Users/cltbld', 05:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:07 INFO - 'LOGNAME': 'cltbld', 05:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:07 INFO - 'MOZ_NO_REMOTE': '1', 05:14:07 INFO - 'NO_EM_RESTART': '1', 05:14:07 INFO - 'PAGER': '/bin/cat', 05:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:07 INFO - 'PWD': '/builds/slave/test', 05:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:07 INFO - 'SHELL': '/bin/bash', 05:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:07 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:07 INFO - 'USER': 'cltbld', 05:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:07 INFO - 'XPC_FLAGS': '0x0', 05:14:07 INFO - 'XPC_SERVICE_NAME': '0', 05:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:14:07 INFO - Cleaning up... 05:14:07 INFO - Return code: 0 05:14:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:14:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:07 INFO - 'HOME': '/Users/cltbld', 05:14:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:07 INFO - 'LOGNAME': 'cltbld', 05:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:07 INFO - 'MOZ_NO_REMOTE': '1', 05:14:07 INFO - 'NO_EM_RESTART': '1', 05:14:07 INFO - 'PAGER': '/bin/cat', 05:14:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:07 INFO - 'PWD': '/builds/slave/test', 05:14:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:07 INFO - 'SHELL': '/bin/bash', 05:14:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:07 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:07 INFO - 'USER': 'cltbld', 05:14:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:07 INFO - 'XPC_FLAGS': '0x0', 05:14:07 INFO - 'XPC_SERVICE_NAME': '0', 05:14:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:08 INFO - Downloading/unpacking psutil>=3.1.1 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:14:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:13 INFO - Installing collected packages: psutil 05:14:13 INFO - Running setup.py install for psutil 05:14:13 INFO - building 'psutil._psutil_osx' extension 05:14:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:14:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:14:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:14:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:14:14 INFO - building 'psutil._psutil_posix' extension 05:14:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:14:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:14:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:14:14 INFO - ^ 05:14:14 INFO - 1 warning generated. 05:14:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:14:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:14:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:14:14 INFO - Successfully installed psutil 05:14:14 INFO - Cleaning up... 05:14:14 INFO - Return code: 0 05:14:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:14 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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:14:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:14 INFO - 'HOME': '/Users/cltbld', 05:14:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:14 INFO - 'LOGNAME': 'cltbld', 05:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:14 INFO - 'MOZ_NO_REMOTE': '1', 05:14:14 INFO - 'NO_EM_RESTART': '1', 05:14:14 INFO - 'PAGER': '/bin/cat', 05:14:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:14 INFO - 'PWD': '/builds/slave/test', 05:14:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:14 INFO - 'SHELL': '/bin/bash', 05:14:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:14 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:14 INFO - 'USER': 'cltbld', 05:14:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:14 INFO - 'XPC_FLAGS': '0x0', 05:14:14 INFO - 'XPC_SERVICE_NAME': '0', 05:14:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:14:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:14:17 INFO - Installing collected packages: mozsystemmonitor 05:14:17 INFO - Running setup.py install for mozsystemmonitor 05:14:17 INFO - Successfully installed mozsystemmonitor 05:14:17 INFO - Cleaning up... 05:14:18 INFO - Return code: 0 05:14:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14: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', '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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:14: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 jsonschema==2.5.1 05:14:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:18 INFO - 'HOME': '/Users/cltbld', 05:14:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:18 INFO - 'LOGNAME': 'cltbld', 05:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:18 INFO - 'MOZ_NO_REMOTE': '1', 05:14:18 INFO - 'NO_EM_RESTART': '1', 05:14:18 INFO - 'PAGER': '/bin/cat', 05:14:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:18 INFO - 'PWD': '/builds/slave/test', 05:14:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:18 INFO - 'SHELL': '/bin/bash', 05:14:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:18 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:18 INFO - 'USER': 'cltbld', 05:14:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:18 INFO - 'XPC_FLAGS': '0x0', 05:14:18 INFO - 'XPC_SERVICE_NAME': '0', 05:14:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:18 INFO - Downloading/unpacking jsonschema==2.5.1 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:14:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:14:21 INFO - warning: no files found matching '*.txt' 05:14:21 INFO - no previously-included directories found matching 'build' 05:14:21 INFO - no previously-included directories found matching 'dist' 05:14:21 INFO - no previously-included directories found matching '.git*' 05:14:21 INFO - Installing collected packages: jsonschema, functools32 05:14:21 INFO - Running setup.py install for functools32 05:14:21 INFO - warning: no files found matching '*.txt' 05:14:21 INFO - no previously-included directories found matching 'build' 05:14:21 INFO - no previously-included directories found matching 'dist' 05:14:21 INFO - no previously-included directories found matching '.git*' 05:14:21 INFO - Successfully installed jsonschema functools32 05:14:21 INFO - Cleaning up... 05:14:21 INFO - Return code: 0 05:14:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:14:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:21 INFO - 'HOME': '/Users/cltbld', 05:14:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:21 INFO - 'LOGNAME': 'cltbld', 05:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:21 INFO - 'MOZ_NO_REMOTE': '1', 05:14:21 INFO - 'NO_EM_RESTART': '1', 05:14:21 INFO - 'PAGER': '/bin/cat', 05:14:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:21 INFO - 'PWD': '/builds/slave/test', 05:14:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:21 INFO - 'SHELL': '/bin/bash', 05:14:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:21 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:21 INFO - 'USER': 'cltbld', 05:14:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:21 INFO - 'XPC_FLAGS': '0x0', 05:14:21 INFO - 'XPC_SERVICE_NAME': '0', 05:14:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:14:22 INFO - Cleaning up... 05:14:22 INFO - Return code: 0 05:14:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:22 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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:14:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:22 INFO - 'HOME': '/Users/cltbld', 05:14:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:22 INFO - 'LOGNAME': 'cltbld', 05:14:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:22 INFO - 'MOZ_NO_REMOTE': '1', 05:14:22 INFO - 'NO_EM_RESTART': '1', 05:14:22 INFO - 'PAGER': '/bin/cat', 05:14:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:22 INFO - 'PWD': '/builds/slave/test', 05:14:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:22 INFO - 'SHELL': '/bin/bash', 05:14:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:22 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:22 INFO - 'USER': 'cltbld', 05:14:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:22 INFO - 'XPC_FLAGS': '0x0', 05:14:22 INFO - 'XPC_SERVICE_NAME': '0', 05:14:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:22 INFO - Downloading/unpacking blobuploader==1.2.4 05:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:25 INFO - Downloading blobuploader-1.2.4.tar.gz 05:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:14:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:14:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:14:26 INFO - Downloading docopt-0.6.1.tar.gz 05:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:14:26 INFO - Installing collected packages: blobuploader, requests, docopt 05:14:26 INFO - Running setup.py install for blobuploader 05:14:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:14:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:14:27 INFO - Running setup.py install for requests 05:14:27 INFO - Running setup.py install for docopt 05:14:27 INFO - Successfully installed blobuploader requests docopt 05:14:27 INFO - Cleaning up... 05:14:27 INFO - Return code: 0 05:14:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:27 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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:27 INFO - 'HOME': '/Users/cltbld', 05:14:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:27 INFO - 'LOGNAME': 'cltbld', 05:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:27 INFO - 'MOZ_NO_REMOTE': '1', 05:14:27 INFO - 'NO_EM_RESTART': '1', 05:14:27 INFO - 'PAGER': '/bin/cat', 05:14:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:27 INFO - 'PWD': '/builds/slave/test', 05:14:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:27 INFO - 'SHELL': '/bin/bash', 05:14:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:27 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:27 INFO - 'USER': 'cltbld', 05:14:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:27 INFO - 'XPC_FLAGS': '0x0', 05:14:27 INFO - 'XPC_SERVICE_NAME': '0', 05:14:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Ju5Nb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-YsRA7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-bYc7mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-rDoQ_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-gewxsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-YwiTA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-tDheqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-o2Ms5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Gu1Lqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:28 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8U7K9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-TOhnhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-K8AKCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-9g5kXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-NUy0OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-BX5juH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-eYz8BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:29 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-LIHQVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:14:29 INFO - Running setup.py install for manifestparser 05:14:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:14:29 INFO - Running setup.py install for mozcrash 05:14:30 INFO - Running setup.py install for mozdebug 05:14:30 INFO - Running setup.py install for mozdevice 05:14:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Running setup.py install for mozfile 05:14:30 INFO - Running setup.py install for mozhttpd 05:14:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Running setup.py install for mozinfo 05:14:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Running setup.py install for mozInstall 05:14:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:14:30 INFO - Running setup.py install for mozleak 05:14:31 INFO - Running setup.py install for mozlog 05:14:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:14:31 INFO - Running setup.py install for moznetwork 05:14:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:14:31 INFO - Running setup.py install for mozprocess 05:14:31 INFO - Running setup.py install for mozprofile 05:14:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:14:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:14:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:14:31 INFO - Running setup.py install for mozrunner 05:14:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:14:31 INFO - Running setup.py install for mozscreenshot 05:14:32 INFO - Running setup.py install for moztest 05:14:32 INFO - Running setup.py install for mozversion 05:14:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:14:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:14:32 INFO - Cleaning up... 05:14:32 INFO - Return code: 0 05:14:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:32 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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:32 INFO - 'HOME': '/Users/cltbld', 05:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:32 INFO - 'LOGNAME': 'cltbld', 05:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:32 INFO - 'MOZ_NO_REMOTE': '1', 05:14:32 INFO - 'NO_EM_RESTART': '1', 05:14:32 INFO - 'PAGER': '/bin/cat', 05:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:32 INFO - 'PWD': '/builds/slave/test', 05:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:32 INFO - 'SHELL': '/bin/bash', 05:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:32 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:32 INFO - 'USER': 'cltbld', 05:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:32 INFO - 'XPC_FLAGS': '0x0', 05:14:32 INFO - 'XPC_SERVICE_NAME': '0', 05:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:14:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-JNGZor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:14:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-VaeDq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:14:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-FE10d_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:14:32 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-j46sxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-zABQRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-EJKhDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-jkv63_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-FxnwQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-xDsu_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-6f8fKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-z5pxIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-qDIeHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:14:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:14:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:14:33 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-j2w2_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:14:34 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GXTQUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:34 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-DHCu5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:14:34 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-mUbZ8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:14:34 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-EE7Q1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:14:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14: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 05:14:37 INFO - Downloading blessings-1.6.tar.gz 05:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:14:37 INFO - Installing collected packages: blessings 05:14:37 INFO - Running setup.py install for blessings 05:14:37 INFO - Successfully installed blessings 05:14:37 INFO - Cleaning up... 05:14:37 INFO - Return code: 0 05:14:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:14:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:37 INFO - Reading from file tmpfile_stdout 05:14:37 INFO - Current package versions: 05:14:37 INFO - blessings == 1.6 05:14:37 INFO - blobuploader == 1.2.4 05:14:37 INFO - docopt == 0.6.1 05:14:37 INFO - functools32 == 3.2.3-2 05:14:37 INFO - jsonschema == 2.5.1 05:14:37 INFO - manifestparser == 1.1 05:14:37 INFO - mozInstall == 1.12 05:14:37 INFO - mozcrash == 1.0 05:14:37 INFO - mozdebug == 0.1 05:14:37 INFO - mozdevice == 0.49 05:14:37 INFO - mozfile == 1.2 05:14:37 INFO - mozhttpd == 0.7 05:14:37 INFO - mozinfo == 0.9 05:14:37 INFO - mozleak == 0.1 05:14:37 INFO - mozlog == 3.4 05:14:37 INFO - moznetwork == 0.27 05:14:37 INFO - mozprocess == 0.24 05:14:37 INFO - mozprofile == 0.28 05:14:37 INFO - mozrunner == 6.13 05:14:37 INFO - mozscreenshot == 0.1 05:14:37 INFO - mozsystemmonitor == 0.3 05:14:37 INFO - moztest == 0.8 05:14:37 INFO - mozversion == 1.4 05:14:37 INFO - psutil == 3.1.1 05:14:37 INFO - requests == 1.2.3 05:14:37 INFO - wsgiref == 0.1.2 05:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:37 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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:37 INFO - 'HOME': '/Users/cltbld', 05:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:37 INFO - 'LOGNAME': 'cltbld', 05:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:37 INFO - 'MOZ_NO_REMOTE': '1', 05:14:37 INFO - 'NO_EM_RESTART': '1', 05:14:37 INFO - 'PAGER': '/bin/cat', 05:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:37 INFO - 'PWD': '/builds/slave/test', 05:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:37 INFO - 'SHELL': '/bin/bash', 05:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:37 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:37 INFO - 'USER': 'cltbld', 05:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:37 INFO - 'XPC_FLAGS': '0x0', 05:14:37 INFO - 'XPC_SERVICE_NAME': '0', 05:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:14:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:14:37 INFO - Cleaning up... 05:14:38 INFO - Return code: 0 05:14:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:14:38 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 0x11046db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110470768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa39579630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, '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.wAQfVCPO7h/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.LpWDYo9B63/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:14:38 INFO - 'HOME': '/Users/cltbld', 05:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:14:38 INFO - 'LOGNAME': 'cltbld', 05:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:14:38 INFO - 'MOZ_NO_REMOTE': '1', 05:14:38 INFO - 'NO_EM_RESTART': '1', 05:14:38 INFO - 'PAGER': '/bin/cat', 05:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:14:38 INFO - 'PWD': '/builds/slave/test', 05:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:14:38 INFO - 'SHELL': '/bin/bash', 05:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:14:38 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:14:38 INFO - 'USER': 'cltbld', 05:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:14:38 INFO - 'XPC_FLAGS': '0x0', 05:14:38 INFO - 'XPC_SERVICE_NAME': '0', 05:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:14:38 INFO - Cleaning up... 05:14:38 INFO - Return code: 0 05:14:38 INFO - Running post-action listener: _resource_record_post_action 05:14:38 INFO - Running post-action listener: _start_resource_monitoring 05:14:38 INFO - Starting resource monitoring. 05:14:38 INFO - [mozharness: 2017-02-01 13:14:38.304710Z] Finished create-virtualenv step (success) 05:14:38 INFO - [mozharness: 2017-02-01 13:14:38.305668Z] Running install step. 05:14:38 INFO - Running pre-action listener: _resource_record_pre_action 05:14:38 INFO - Running main action method: install 05:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:14:38 INFO - Reading from file tmpfile_stdout 05:14:38 INFO - Detecting whether we're running mozinstall >=1.0... 05:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:14:38 INFO - Reading from file tmpfile_stdout 05:14:38 INFO - Output received: 05:14:38 INFO - Usage: mozinstall [options] installer 05:14:38 INFO - Options: 05:14:38 INFO - -h, --help show this help message and exit 05:14:38 INFO - -d DEST, --destination=DEST 05:14:38 INFO - Directory to install application into. [default: 05:14:38 INFO - "/builds/slave/test"] 05:14:38 INFO - --app=APP Application being installed. [default: firefox] 05:14:38 INFO - mkdir: /builds/slave/test/build/application 05:14:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:15:00 INFO - Reading from file tmpfile_stdout 05:15:00 INFO - Output received: 05:15:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:15:00 INFO - Running post-action listener: _resource_record_post_action 05:15:00 INFO - [mozharness: 2017-02-01 13:15:00.890231Z] Finished install step (success) 05:15:00 INFO - [mozharness: 2017-02-01 13:15:00.890368Z] Running run-tests step. 05:15:00 INFO - Running pre-action listener: _resource_record_pre_action 05:15:00 INFO - Running main action method: run_tests 05:15:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:00 INFO - 2017-02-01 05:15:00.904 screenresolution[1625:8298] starting screenresolution argv=screenresolution get 05:15:00 INFO - 2017-02-01 05:15:00.907 screenresolution[1625:8298] Display 0: 1600x1200x32@60 05:15:00 INFO - 2017-02-01 05:15:00.913 screenresolution[1626:8300] starting screenresolution argv=screenresolution list 05:15:00 INFO - Available Modes on Display 0 05:15:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:01 INFO - Intel Iris: 05:15:01 INFO - Chipset Model: Intel Iris 05:15:01 INFO - Type: GPU 05:15:01 INFO - Bus: Built-In 05:15:01 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:01 INFO - Vendor: Intel (0x8086) 05:15:01 INFO - Device ID: 0x0a2e 05:15:01 INFO - Revision ID: 0x0009 05:15:01 INFO - Displays: 05:15:01 INFO - Display: 05:15:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:01 INFO - Display Serial Number: 1600x1200 60 05:15:01 INFO - Main Display: Yes 05:15:01 INFO - Mirror: Off 05:15:01 INFO - Online: Yes 05:15:01 INFO - Rotation: Supported 05:15:01 INFO - Return code: 0 05:15:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:15:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:15:01 INFO - Python 2.7.3 05:15:01 INFO - Return code: 0 05:15:01 INFO - grabbing minidump binary from tooltool 05:15:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:15:01 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 0x11024bd30>, '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 0x110172db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110240be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:15:01 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:15:01 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:15:01 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:15:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:15:01 INFO - Return code: 0 05:15:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:15:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:15:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:15:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:15:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:15:01 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/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:15:01 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/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0209 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wAQfVCPO7h/Render', 05:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:01 INFO - 'HOME': '/Users/cltbld', 05:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:01 INFO - 'LOGNAME': 'cltbld', 05:15:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:15:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:01 INFO - 'MOZ_NO_REMOTE': '1', 05:15:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:15:01 INFO - 'NO_EM_RESTART': '1', 05:15:01 INFO - 'PAGER': '/bin/cat', 05:15:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:01 INFO - 'PWD': '/builds/slave/test', 05:15:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:01 INFO - 'SHELL': '/bin/bash', 05:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LpWDYo9B63/Listeners', 05:15:01 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 05:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:01 INFO - 'USER': 'cltbld', 05:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:01 INFO - 'XPC_FLAGS': '0x0', 05:15:01 INFO - 'XPC_SERVICE_NAME': '0', 05:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:01 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/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:15:01 INFO - mozversion application_buildid: 20170201041209 05:15:01 INFO - mozversion application_changeset: ae91b2b5bb69ffd18b4679188730d0c6af3b4a95 05:15:01 INFO - mozversion application_display_name: Nightly 05:15:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:15:01 INFO - mozversion application_name: Firefox 05:15:01 INFO - mozversion application_remotingname: firefox 05:15:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:15:01 INFO - mozversion application_vendor: Mozilla 05:15:01 INFO - mozversion application_version: 54.0a1 05:15:01 INFO - mozversion platform_buildid: 20170201041209 05:15:01 INFO - mozversion platform_changeset: ae91b2b5bb69ffd18b4679188730d0c6af3b4a95 05:15:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:15:01 INFO - mozversion platform_version: 54.0a1 05:15:01 INFO - using testdate: 1485954901 05:15:01 INFO - actual date: 1485954901 05:15:01 INFO - starting webserver on 'localhost:49247' 05:15:01 INFO - SUITE-START | Running 2 tests 05:15:01 INFO - TEST-START | dromaeo_css 05:15:01 INFO - operating with platform_type : mac_ 05:15:01 INFO - Initialising browser for dromaeo_css test... 05:15:01 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpMCRAhK/profile http://localhost:49247/getInfo.html) 05:15:03 INFO - PROCESS | 1636 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:15:03 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 05:15:03 INFO - PROCESS | 1636 | colorDepth:24 05:15:03 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 05:15:03 INFO - PROCESS | 1636 | __metrics 05:15:03 INFO - PROCESS | 1636 | 1485954903891 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:15:03 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:15:03 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:15:04 INFO - TEST-INFO | 1636: exit 0 05:15:04 INFO - Browser initialized. 05:15:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:15:04 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpMCRAhK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:16 INFO - PROCESS | 1639 | RSS: Main: 237158400 05:15:16 INFO - PROCESS | 1639 | 05:18:19 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 05:18:22 INFO - PROCESS | 1639 | RSS: Main: 477618176 05:18:22 INFO - PROCESS | 1639 | 05:20:06 INFO - PROCESS | 1639 | ************************* 05:20:06 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 05:20:06 INFO - PROCESS | 1639 | 05:20:06 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:20:06 INFO - PROCESS | 1639 | Full stack: 05:20:06 INFO - PROCESS | 1639 | ************************* 05:21:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 05:21:25 INFO - PROCESS | 1639 | RSS: Main: 369651712 05:21:25 INFO - PROCESS | 1639 | 05:24:27 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 05:24:28 INFO - PROCESS | 1639 | RSS: Main: 366321664 05:24:28 INFO - PROCESS | 1639 | 05:27:31 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 05:27:31 INFO - PROCESS | 1639 | RSS: Main: 348475392 05:27:31 INFO - PROCESS | 1639 | 05:30:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 05:30:35 INFO - PROCESS | 1639 | RSS: Main: 337739776 05:30:35 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 05:33:39 INFO - PROCESS | 1639 | RSS: Main: 407117824 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | __start_tp_report 05:33:39 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 05:33:39 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 05:33:39 INFO - PROCESS | 1639 | |i|pagename|runs| 05:33:39 INFO - PROCESS | 1639 | |0;dojo.html;3317.137544238498;3328.9001329960092;3342.181165614425;3346.7657263991523;3386.05190546647;5603.859903502414;5613.101903695405;5664.291963504556;5670.064439367499;5681.772729090837;5664.150377443386;5707.914381284284;5783.826485205451;5793.275840519935;5825.5048320892665;5136.383633963929;5156.948430515703;5190.013897359489;5253.238280449333;5290.497402746733;5310.8406747797435;5312.840614781543;5323.840284791443;5329.546988505962;5375.731213439332;5136.332276804029;5295.179247216687;5321.6540924839765;5324.1215199492035;5350.3312085989255;4337.175936572041;5159.845204643867;5175.96480703857;5230.790768369298;5239.842804715865;5184.611154163415;5189.2475591039465;5201.297824793643;5222.2427747976335;5257.13257312543;4855.465898751135;5702.230198923134;5721.856953576153;5726.3700992890745;5762.250907382056;6117.553376797881;6183.041628547582;6192.071189321638;6243.1883855099;6246.718897649616;4960.8759781005765;5432.972835135839;6293.653849038301;6310.5898116622275;6330.145430366888;5814.302283725895;5860.853478663067;5872.765089396419;5874;5890.587658863924;6215.81352559424;6241.21984751906;6245.687715614201;6284.340144284875;6299.433051025429;6136.274005701014;6167.907481387782;6191.102290167901;6201.1938448002375;6206.627602343874;6112.329598297095;6229.875402491924;6243.687815609202;6244.1570387997945;6250.96859018266;6127.250613123855;6141.792871882774;6145.907811382833;6183.690815459209;6217.9689101555105;6212;6214.627122372672;6216.471599914058;6224.688765561795;6224.941759900737;4976.900461990812;4996.150654388763;4997.925031124463;5000.549950504472;5019.447860735316;4408.4930233023615;4431.423914891044;4434.600885920218;4476.216662084123;4517.774111294488;5003;5722.456366645162;5802.071668533099;5815.244018277597;5853.9414605854245;6159.5688301818445;6203.31763506014;6223.471004964629;6225.968870155621;6298.622082675054;5588.636738611977;5675.886482270332;5805.983952808242;5806.303243610793;5814.854628634318;5412.621116521885;5419.376771671229;5447.210154527651;5447.754851031713;5517.503424691797;6254.6247225166635;6290.370962903673;6300.2754683212015;6302.432781049728;6312.936870631235;6133.877322453437;6303.621782693053;6306.936930630635;6316.7473301069285;6325.873482530232;6217.533684973736;6218.844528886814;6239.594426362272;6287.811365659038;6299.622022678654;6242.438180563771;6243.063540468785;6249.437550620466;6252.437280644764;6269.216347956505;6143.139960405458;6275.309715931336;6286.622802631857;6305.684715764285;6314.6842657866;5982.940170598238;6044.002739547926;6051.12258722474;6106.358832322542;6113;6049.939500605114;6066.7269972850445;6076.422739839708;6092.543059270661;6099.359567245376;5931.9406805931385;5941.851453713692;5952.553558483044;5956.8808623828145;5965.642061476326;6114.510839132948;6117.816465506042;6120.204373431426;6135.969320153371;6139;5927.407259274038;5930.703464826655;5936.821895343147;5938.287405511366;5939.673317967553 05:33:39 INFO - PROCESS | 1639 | |1;ext.html;482.25732372146894;501.1280372152455;506.2077848167615;512.5284738041001;542.9185622156676;38366.424503632465;39957.20085598286;40223.396649050286;40261.798691006574;41949;40845.59154408455;41173.38239926396;41256;41260.58739412612;41427;8408.285295749869;8634.654613815439;9204.769880752985;9405.670801521948;9420.293477989144;11050.834237486444;11369.829452558219;11371.431428428587;11552.942235288812;11583.073354131691;11400.771984560304;11458.427078646077;11486.425678716072;11493.367864767435;11522.308661480296;8437;8640.524771137585;8640.697575584825;11360.091192704564;11447.141464390163;8490.957545212295;11054.557817687282;11314.37770922599;11364.374959377232;11553.942230288794;39104.21791564153;39152.41271380903;39180;39272.607273927184;39861.81796366483;4003.3594624860175;4004.6796256299426;4030.8186131624147;4056.71602987791;4168.7081904266715;25005.874970625213;29435.11694649164;30431;30528.694713052806;30546.389072218655;3118.953215701766;3127.53087036944;3207.134073800084;3276.836158192105;3284.0476261884023;469738.99999999657;470433;470747;471674;472791;59557.702211488664;59936.50158746066;59936.70031649901;60001.79996400048;60042.69978650166;63472.68263658652;63562;63677.72644547176;63678.36321636817;63752.68123659352;13778.793318100237;15502.837287203505;15974.281157347947;15988.600284992968;16047;11129.554817807359;11393.886061139448;11401.144914131473;11556.017738492154;12358.196717213352;13648.454061837416;14872.925635371754;14952.850471495145;16683.582910427336;16684.6663066738;5613.915791263053;5718.913406452761;5838.620489668158;5967.19442875215;6834.188939930922;858.8319884956579;862.2756884217288;888.7289376740027;888.7911340834899;901.5312037740339;29302.70697293;29507;29644.703552964627;30078.54882176769;30377;1013.8543445906079;1038.408107378802;1090.0843291635065;1093.7375029992745;1149.6378640728144;13327.866721332857;14407.78388324176;15163.119007388561;15287.541373758804;15469.381224750883;579865;580679;581107;583142;584924;58731.119033214534;59142;59150.70424647849;59154.70422647859;59232.70383648054;1254.44177341086;1375.2504884837788;1392.693607406369;1405.4729476446332;1420.8081908942368;28551.857240714493;28753.137405877857;28859.42281154407;29602.111936641933;29734.85132574323;49522;49875.75062124666;49879;49931.251031233835;49936;482537;486275;486605;487128;487796;38608;39083;39366;39415.40876886906;39625.40561891632;37143.25713485769;43002;44053;45807;46596.53403465922;38322.616773831905;38368.65709700147;38661.6133838658;39243;40036.59963400329;37529.4370584436;37964.62035379611;38511.80744096261;38550.42174367443;39167.608323917535;36814.8159259202;37129.62870371262;37393.813030934674;37764;37950;32713;33531.66468335383;34329.48505772366;34358.48462273018;34497;24691;24965.12622058292;24993.50012999766;25026.62460063064;25125.012451438804 05:33:39 INFO - PROCESS | 1639 | |2;jquery.html;332.46141251173106;366.15783697495755;395.1661993194359;403.4976454314379;430.24921511961634;12138;15526.223688815557;17423.73864392032;17616.65283596116;21000.84495352755;20830.687539686878;21674.674879876813;21999.560008799814;22061.889690551525;22572.88713556434;4999.225120106371;5733.856653583663;5943.167956486095;5977.6114552553945;5978.372270911557;4633.8841528961875;5864.208331875185;5874.383189765077;5877.059670452729;5881.117832325164;5626.465485778865;5665.3768085510565;5760.884782304352;5836.15375770513;5844.736986835582;5775.1337299405;5796.188533605298;5815.592908496407;5817.447342502455;5851.15158302046;4428.22506061438;4914.655974081816;5085.211792172219;5121.4110377306715;5156.226566015091;25980.090696825522;27119.593206101923;27181.320466988287;27242.182734517995;27423.45153096927;2697.7167397423186;2746.835189888613;2755.118362124121;2809.1712944681367;2826.4771017361854;7770.611469426506;7952.181662024858;15927.840721592751;16016.4394246202;16301.836981630151;1687.8987260764393;1713.8971661700339;1795.9852683234062;1816.946171220689;1822.6992546229856;126957;130628;131025;132055;133034.6696533032;8385.119562445978;10613.734656633645;11296.265742726697;11404.657860264206;11539.826902596467;14855.2572371381;14874.32262192794;14882.139732533433;14884.627884302763;14904.9566530344;7243.203247642755;8654.35092368075;8676.123518743969;8684.261837743863;8800.735977920673;7009.509334346649;8413.579321033923;8512.70205542803;8681;8709.520976346235;7070.292970702889;7074.257202993715;7083.220845706968;7187.676554555058;7238.059052323164;1713.3660545597959;1831.1485159400943;1938.951526211856;1939.679952807799;1948.3083505355678;1052.7210289273348;1060;1070.2240875365453;1073.511552243743;1114.420501339299;17200.655986880192;22538;22743.09027638909;23055.88472057629;23385.41536461568;1077.7197928538637;1085.3270971997413;1114.6154576671036;1118.8601424821898;1123.0790751583622;11181.44092795357;11824.52701891932;12119.454624541917;12131;12198.817017744741;709027;710367;718530;728293;729552;91892.54053729822;93468.59797103048;93939;122118;128414;995.9053889880449;996.8953259907895;1037.061459379263;1037.444966942682;1037.527924794217;19627.509312267306;19628.803711963268;19738.210471580976;19998;20052;35026.67246611568;43949.901252468946;49885.501144988084;54838.725806372306;55523;74820.25179748132;75234.6238268827;76401.23598764163;76652.61673691595;78760.21239787758;8101.8379632408205;8143.714969976022;8292.502449853027;8335.708250211213;8582.757674754932;13448.394822232827;13484.460621575026;13714;13908;13911.373988170759;11171.329720216812;11464.426778660867;11672.299662020307;11837.585684501;11840.1711880171;10034.297599167989;10629.840552391566;10651.147908167173;10654.45510401003;11626.302421854716;9940.751481213027;11445.713857153638;11772.117091218022;12010.879891200977;12058.733832947764;8051.953246077972;8145.5486697032675;8377.45546539473;8519.531425771642;8553.187447192508;10485.318454300446;11372.658820235407;11398.658040258806;11404.48679809394;11894.34581098048 05:33:39 INFO - PROCESS | 1639 | |3;mootools.html;1308.548550749991;1412.6468382904275;1424.7150569886023;1432.6991331820318;1441.3946142620098;7433.033705618269;7768.378529717623;7768.417368697342;7768.883466747996;7776.533407995525;8052.315553177972;8095.06906705729;8142.7557173284895;8204.95897520513;8235.752927412172;2247.292102987562;2291.4385975436044;2292.197730794217;2292.931212063641;2296.4144143243448;2039.3066357438468;2232.743234528026;2288.965665515019;2301.2750983440246;2304.5966955782756;2050.7231523744254;2080.646290130674;2200.7909248621354;2270.863748175115;2277.237125562938;1765.5233087066483;2085.8018488243742;2121.3635909227323;2123.288698286076;2127.8084972352563;1655.5778276539406;2087.676410156428;2236.9664455033026;2296.9425764355856;2297.356740112771;7018.298170182992;7629.961850190769;8130.55281959492;8312.41813073079;8323.66705331786;5020.3724534433195;5180.222966555009;5180.766865491024;5182.378114626231;5187.896242075138;3495;3497.1082373994723;3497.91255218619;3578.409562422223;3578.8568457261676;1057.349729916096;1225.91418600698;1229.6372570091844;1270.646761199329;1275.7193417448148;10703.090237329836;10786.244962852603;10797.028267455888;10806.35161890289;10850.782984340269;4301.268784306675;4584.724916505021;4649.674522783373;4668.5564871337165;4677.836275730401;9329.580168892417;9366.391184572982;9371.203447706885;9377.624895004259;9395.154436100784;5198.324217851655;5206.219067139959;5210.713410762444;5213.165893457029;5214.191800270888;4511.661370585743;4673.158831410311;4710.976445117821;4763.95236047642;4765.809367625326;3753.512043434336;3790.488284081669;3795.7153213509096;3840.6543411093135;3869.8839034829;1202.9053561259386;1253.5612535612645;1257.993710031444;1258.7860063789178;1274.7514234724326;794.8728203487415;842.5029232752705;880.471716969813;921.1985572551789;926.4672813132476;5001.7499125043605;5722.341930677942;5813;5989.161517387569;6126.356732543108;889.2707979456783;966.4877614864133;994.9353292035994;1001.9348742331725;1026.0509029148009;3327.3678001179696;3661.761985470989;3850.7689538627774;3907.941380879289;3935.685145188378;9006.50464224474;9409.90590094104;9443.150116489549;9696.466694331739;9894.356866803635;9666.95166524177;9670.371425857296;9778.168855647207;9984.700458986243;9986.900130998742;7667.079950405987;7816.804579885548;7829.921700783033;7837.333826624801;7841.921580784119;4385.956140438555;5703.173039909107;5731.7420716067045;5831.650100993954;5924.4667979882015;8589.65641374338;8921.375503714678;8930.19628233462;9028.187463128088;9124.817503650023;9113.407628504126;9267.304952128752;9268.073192680678;9363.719088427359;9381.81236375255;7061.964690176516;7185.892211616934;7196.820079498055;7209.315115064061;7216.3505284524645;7597.582132982739;7734.6132693366235;7812.179721129427;7842.176571459914;7849.01887264092;6990.056342393712;7010.649467526501;7038.331358520933;7040.577565346096;7210.927890721236;6918.169819621678;6931.93068069333;6971.8257043572285;7034.120734908137;7053.400460960671;6096.847578810565;6292.968535157295;6353.745850165941;6424.164858568543;6432.485401167989;4371.781410929504;4899.069176856386;4993.6254780892305;5026.472220416804;5030.773615187399;4953.108440480748;5051.676228934732;5052.621053421081;5183.429822719573;5216.843494695166 05:33:39 INFO - PROCESS | 1639 | |4;prototype.html;305.3115225167248;329.85321531918294;361.1765175400087;370.2373111390535;374.0797637810985;4722.340186355576;5015.5486006259425;5388.622796404248;5404.621676482648;5475.917861232075;4074.9592504075026;4364.32352985287;4893.923336865883;5128.53843154117;5273.815416460425;4119.382092686091;4155.833766649331;4179.143275628504;4213.682474638952;4238.449001629784;3709.5733990591025;4176.540580536153;4214.431051807997;4221.7889105544755;4278.465191851019;3631.2737452509473;3691.446283057536;3891.385075193791;4161.854335098272;4170.8540201092965;4157.334826427757;4158.7296825706235;4163.980469472303;4168.6665066794885;4171.478565179353;3681.895431370605;3690.2435000824767;3694.538182727159;3695.5565332160045;3711.832967516468;4800.175823297598;4962.429320628138;5024.96988117435;5195.558377537914;5255.505089115772;4283.7429754214845;4491.752953587551;4565.885852853705;4824.079438972264;4856.587190088847;4953.23224900141;5617.365673319487;5653.236813030229;5655.8586035349035;5702.857428564277;3372.2749608833974;3932.390479475686;4080.8575497863562;4090;4129.256733787917;5073.524635624609;5151.5106064923775;5728.656280623134;5810.941890581083;5842.357340692521;3822.9869084692577;4153.563875793058;4174.603412675791;4180.728252663567;4190.575204430447;4000.979995100006;4200.180964711852;4241;4254.808533615995;4269.270945267172;4224.45082139326;4411.360352748833;4570.451545814523;4580.244259697112;4601.5168407317415;4786.497417771154;5308.628396012242;5636.520895723909;5658.830235092953;5671.092625180034;1090.1333439915286;1164.6273192578462;1222.7554489102038;1236.6166488388628;1279.9936000320124;836.9412692943431;848.7099608595024;857.4298091769028;865.0009239328585;869.8477766390856;4104.15864747726;4913.010230856509;4926.679765815211;4960.429550601727;4970.365706175733;4757.573637444928;4919.508049195123;4948.777305021283;5412.484504338791;5454.945450545523;4357.607815296575;4455.420795296591;4589.632829373642;4717.820544863784;4828.272026305126;2527.8356906801;2840.829550226993;2844.6586409630977;2855.528837741791;2857.5999360089604;4370.6066454018655;4998.625652814884;5323.494268044529;5346.064438723208;5594.608377413542;4693.22561777303;4935.901281974412;5116.623028800974;5524.364698059694;5557.444255574491;5111.412187598399;5408.107662235766;5567.415421380859;5572.3870374258795;5575.498205161555;4855.417349917891;5332.82677810881;5369.5167434931045;5407.270018547603;5424.864378390572;5108.284840122312;5728.598998070095;5755.165501002248;5786.797462088975;5815.82552523425;5999.740054588411;6013.849653758516;6019.939800601938;6032.728527216198;6061.393860613904;3169.904902852918;3626.437902125175;3820.6179382061573;3913.706472014553;3957.406389041667;5364.088105022233;5773.971130144322;5777.740001700017;5807.82576522705;5809.448102430262;4979.402471703419;5436.091690945325;5446.482584154499;5460.31746031746;5662.971685141548;4413.492448368479;4999.700077979679;5558.44415558441;5599.488138202744;5658.632188907873;4802.447718512416;5293.894122117613;5294.523492885659;5500.944990550043;5563.944360556505;4957.35554377943;5040.395152581714;5322.361316642027;5630.549556035426;5658.801941932013;4792.065547218259;5302.70835104073;5511.889762204653;5520.3990842655185;5534.225208470737 05:33:39 INFO - PROCESS | 1639 | |5;yui.html;674.7672053141664;703.5145749432891;741.5698894641108;828.9585520723962;844.7127976487994;15059.774103388445;15666.295016724247;15707.136107514068;15712.607184820385;15719.37122515101;13614.455421783117;13651.044426890121;13688.863111368908;13689.520866769664;13711.862881371184;575.9078547432412;669.3172963577149;835.1773503099442;849.9532525711099;865.0786911938796;677.7085853083172;857.9742607721747;862.7972426479772;872.9345299102561;874.6982291109589;923.0907556057278;924.7549399409162;925.6436272035281;926.0924294191697;927.0451435021938;910.658503061352;921.1755478846395;923.0354279777648;925.7870689741397;929.953502324881;522.7412430846717;534.0653855752433;534.3480953237045;537.7311344327836;538.6552606331951;14411.279436028157;14928;14989.325480353411;15282.541523754306;15310.693786124326;13872.930635346758;14479.855201447956;14508.564743057725;14567.490137845125;14794.630134246623;11603.477843497063;11608.477618507188;11690.766184676344;12039.578614748441;12260.570880019246;3711.183539621279;3755.0800053986677;3789.77261364319;3797.0697179191275;3935.330993731073;110468;113798;114001;115751;116207.83792162055;10787.029167374974;10880.347179169275;11002.669919902415;11081.986166204913;11294.265872718248;21540.78459215388;22372.440688982904;22441.55116897653;22575;22681;5942.405759424023;7564.886526702104;7608.31525162738;7685.692572297157;8199.7950051248;4255.722146901625;4293.720908140961;4303.07483890965;4310.396544483775;4492.0791237796175;3603.657652523006;3674.356987527172;3685.539307586552;3699.408094704834;3705.7591256568235;616.4729156571157;633.2938773267773;664.6012392564422;665.6272487407067;685.9382655561024;663.5123184459419;684.1960696181966;697.5988806436319;703.3880523944201;734.7281505842873;8776;9748.902510974942;10280.61211736421;11019.173561982883;11107.24545481645;562.5893098038455;590.8463799412098;596.8358701357075;632.4055387935326;719.7408932784193;4760.857174284777;5037.496250374933;5039.496050394931;5047;5051.217061355495;94034.05965940253;96666;98787.50606246923;99913.50043249737;100310.99689003016;71487.64256178685;71935.64032179806;78980;79217.60391198007;87563;71084;73028;73279;73411.63294183495;73994;10579.15366770641;10616.203784716332;10774.24580279373;10869.239153259196;10880.945595271973;42013.78993105015;42116.78941605272;43546.822658866295;44010.11979760451;45463.77268113638;61472;62186;62448.687756560925;64424.677876610316;67771;12619.305938173122;12806.335176427378;12815;13235;13581.932090339486;13414.597562073153;14204.48588986468;14454;14504.274786260856;15035.849641503883;11816.468258928197;13010;13379.86620133825;13598.388072536562;14180;12775.105742597929;13778.724425511635;13940.721185576434;14184.36170372315;14197.574072777834;12600.55898043564;13065.542706005244;14132.929335353258;14168.574942751733;14475.27623618836;8338.583070846313;11233.269837460539;11601.12991525622;11765.52937882475;11836.644900652995;9815.607375705178;10588.047395162574;11153.107751379708;11499.942500287445;11546.94226528862 05:33:39 INFO - PROCESS | 1639 | __end_tp_report 05:33:39 INFO - PROCESS | 1639 | __start_cc_report 05:33:39 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,2974 05:33:39 INFO - PROCESS | 1639 | __end_cc_report 05:33:39 INFO - PROCESS | 1639 | __startTimestamp1485956019787__endTimestamp 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | ------- Summary: start ------- 05:33:39 INFO - PROCESS | 1639 | Number of tests: 6 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5736.57 Median:5939.67 stddev:645.20 (10.9%) stddev-sans-first:618.63 05:33:39 INFO - PROCESS | 1639 | Values: 3317.1 3328.9 3342.2 3346.8 3386.1 5603.9 5613.1 5664.3 5670.1 5681.8 5664.2 5707.9 5783.8 5793.3 5825.5 5136.4 5156.9 5190.0 5253.2 5290.5 5310.8 5312.8 5323.8 5329.5 5375.7 5136.3 5295.2 5321.7 5324.1 5350.3 4337.2 5159.8 5176.0 5230.8 5239.8 5184.6 5189.2 5201.3 5222.2 5257.1 4855.5 5702.2 5721.9 5726.4 5762.3 6117.6 6183.0 6192.1 6243.2 6246.7 4960.9 5433.0 6293.7 6310.6 6330.1 5814.3 5860.9 5872.8 5874.0 5890.6 6215.8 6241.2 6245.7 6284.3 6299.4 6136.3 6167.9 6191.1 6201.2 6206.6 6112.3 6229.9 6243.7 6244.2 6251.0 6127.3 6141.8 6145.9 6183.7 6218.0 6212.0 6214.6 6216.5 6224.7 6224.9 4976.9 4996.2 4997.9 5000.5 5019.4 4408.5 4431.4 4434.6 4476.2 4517.8 5003.0 5722.5 5802.1 5815.2 5853.9 6159.6 6203.3 6223.5 6226.0 6298.6 5588.6 5675.9 5806.0 5806.3 5814.9 5412.6 5419.4 5447.2 5447.8 5517.5 6254.6 6290.4 6300.3 6302.4 6312.9 6133.9 6303.6 6306.9 6316.7 6325.9 6217.5 6218.8 6239.6 6287.8 6299.6 6242.4 6243.1 6249.4 6252.4 6269.2 6143.1 6275.3 6286.6 6305.7 6314.7 5982.9 6044.0 6051.1 6106.4 6113.0 6049.9 6066.7 6076.4 6092.5 6099.4 5931.9 5941.9 5952.6 5956.9 5965.6 6114.5 6117.8 6120.2 6136.0 6139.0 5927.4 5930.7 5936.8 5938.3 5939.7 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:65635.18 Median:38872.31 stddev:137195.22 (352.9%) stddev-sans-first:137492.88 05:33:39 INFO - PROCESS | 1639 | Values: 482.3 501.1 506.2 512.5 542.9 38366.4 39957.2 40223.4 40261.8 41949.0 40845.6 41173.4 41256.0 41260.6 41427.0 8408.3 8634.7 9204.8 9405.7 9420.3 11050.8 11369.8 11371.4 11552.9 11583.1 11400.8 11458.4 11486.4 11493.4 11522.3 8437.0 8640.5 8640.7 11360.1 11447.1 8491.0 11054.6 11314.4 11364.4 11553.9 39104.2 39152.4 39180.0 39272.6 39861.8 4003.4 4004.7 4030.8 4056.7 4168.7 25005.9 29435.1 30431.0 30528.7 30546.4 3119.0 3127.5 3207.1 3276.8 3284.0 469739.0 470433.0 470747.0 471674.0 472791.0 59557.7 59936.5 59936.7 60001.8 60042.7 63472.7 63562.0 63677.7 63678.4 63752.7 13778.8 15502.8 15974.3 15988.6 16047.0 11129.6 11393.9 11401.1 11556.0 12358.2 13648.5 14872.9 14952.9 16683.6 16684.7 5613.9 5718.9 5838.6 5967.2 6834.2 858.8 862.3 888.7 888.8 901.5 29302.7 29507.0 29644.7 30078.5 30377.0 1013.9 1038.4 1090.1 1093.7 1149.6 13327.9 14407.8 15163.1 15287.5 15469.4 579865.0 580679.0 581107.0 583142.0 584924.0 58731.1 59142.0 59150.7 59154.7 59232.7 1254.4 1375.3 1392.7 1405.5 1420.8 28551.9 28753.1 28859.4 29602.1 29734.9 49522.0 49875.8 49879.0 49931.3 49936.0 482537.0 486275.0 486605.0 487128.0 487796.0 38608.0 39083.0 39366.0 39415.4 39625.4 37143.3 43002.0 44053.0 45807.0 46596.5 38322.6 38368.7 38661.6 39243.0 40036.6 37529.4 37964.6 38511.8 38550.4 39167.6 36814.8 37129.6 37393.8 37764.0 37950.0 32713.0 33531.7 34329.5 34358.5 34497.0 24691.0 24965.1 24993.5 25026.6 25125.0 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:38180.10 Median:24682.75 stddev:118859.07 (481.5%) stddev-sans-first:119158.53 05:33:39 INFO - PROCESS | 1639 | Values: 332.5 366.2 395.2 403.5 430.2 12138.0 15526.2 17423.7 17616.7 21000.8 20830.7 21674.7 21999.6 22061.9 22572.9 4999.2 5733.9 5943.2 5977.6 5978.4 4633.9 5864.2 5874.4 5877.1 5881.1 5626.5 5665.4 5760.9 5836.2 5844.7 5775.1 5796.2 5815.6 5817.4 5851.2 4428.2 4914.7 5085.2 5121.4 5156.2 25980.1 27119.6 27181.3 27242.2 27423.5 2697.7 2746.8 2755.1 2809.2 2826.5 7770.6 7952.2 15927.8 16016.4 16301.8 1687.9 1713.9 1796.0 1816.9 1822.7 126957.0 130628.0 131025.0 132055.0 133034.7 8385.1 10613.7 11296.3 11404.7 11539.8 14855.3 14874.3 14882.1 14884.6 14905.0 7243.2 8654.4 8676.1 8684.3 8800.7 7009.5 8413.6 8512.7 8681.0 8709.5 7070.3 7074.3 7083.2 7187.7 7238.1 1713.4 1831.1 1939.0 1939.7 1948.3 1052.7 1060.0 1070.2 1073.5 1114.4 17200.7 22538.0 22743.1 23055.9 23385.4 1077.7 1085.3 1114.6 1118.9 1123.1 11181.4 11824.5 12119.5 12131.0 12198.8 709027.0 710367.0 718530.0 728293.0 729552.0 91892.5 93468.6 93939.0 122118.0 128414.0 995.9 996.9 1037.1 1037.4 1037.5 19627.5 19628.8 19738.2 19998.0 20052.0 35026.7 43949.9 49885.5 54838.7 55523.0 74820.3 75234.6 76401.2 76652.6 78760.2 8101.8 8143.7 8292.5 8335.7 8582.8 13448.4 13484.5 13714.0 13908.0 13911.4 11171.3 11464.4 11672.3 11837.6 11840.2 10034.3 10629.8 10651.1 10654.5 11626.3 9940.8 11445.7 11772.1 12010.9 12058.7 8052.0 8145.5 8377.5 8519.5 8553.2 10485.3 11372.7 11398.7 11404.5 11894.3 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5359.99 Median:5211.94 stddev:2908.25 (55.8%) stddev-sans-first:2900.46 05:33:39 INFO - PROCESS | 1639 | Values: 1308.5 1412.6 1424.7 1432.7 1441.4 7433.0 7768.4 7768.4 7768.9 7776.5 8052.3 8095.1 8142.8 8205.0 8235.8 2247.3 2291.4 2292.2 2292.9 2296.4 2039.3 2232.7 2289.0 2301.3 2304.6 2050.7 2080.6 2200.8 2270.9 2277.2 1765.5 2085.8 2121.4 2123.3 2127.8 1655.6 2087.7 2237.0 2296.9 2297.4 7018.3 7630.0 8130.6 8312.4 8323.7 5020.4 5180.2 5180.8 5182.4 5187.9 3495.0 3497.1 3497.9 3578.4 3578.9 1057.3 1225.9 1229.6 1270.6 1275.7 10703.1 10786.2 10797.0 10806.4 10850.8 4301.3 4584.7 4649.7 4668.6 4677.8 9329.6 9366.4 9371.2 9377.6 9395.2 5198.3 5206.2 5210.7 5213.2 5214.2 4511.7 4673.2 4711.0 4764.0 4765.8 3753.5 3790.5 3795.7 3840.7 3869.9 1202.9 1253.6 1258.0 1258.8 1274.8 794.9 842.5 880.5 921.2 926.5 5001.7 5722.3 5813.0 5989.2 6126.4 889.3 966.5 994.9 1001.9 1026.1 3327.4 3661.8 3850.8 3907.9 3935.7 9006.5 9409.9 9443.2 9696.5 9894.4 9667.0 9670.4 9778.2 9984.7 9986.9 7667.1 7816.8 7829.9 7837.3 7841.9 4386.0 5703.2 5731.7 5831.7 5924.5 8589.7 8921.4 8930.2 9028.2 9124.8 9113.4 9267.3 9268.1 9363.7 9381.8 7062.0 7185.9 7196.8 7209.3 7216.4 7597.6 7734.6 7812.2 7842.2 7849.0 6990.1 7010.6 7038.3 7040.6 7210.9 6918.2 6931.9 6971.8 7034.1 7053.4 6096.8 6293.0 6353.7 6424.2 6432.5 4371.8 4899.1 4993.6 5026.5 5030.8 4953.1 5051.7 5052.6 5183.4 5216.8 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4476.17 Median:4923.09 stddev:1338.34 (27.2%) stddev-sans-first:1304.97 05:33:39 INFO - PROCESS | 1639 | Values: 305.3 329.9 361.2 370.2 374.1 4722.3 5015.5 5388.6 5404.6 5475.9 4075.0 4364.3 4893.9 5128.5 5273.8 4119.4 4155.8 4179.1 4213.7 4238.4 3709.6 4176.5 4214.4 4221.8 4278.5 3631.3 3691.4 3891.4 4161.9 4170.9 4157.3 4158.7 4164.0 4168.7 4171.5 3681.9 3690.2 3694.5 3695.6 3711.8 4800.2 4962.4 5025.0 5195.6 5255.5 4283.7 4491.8 4565.9 4824.1 4856.6 4953.2 5617.4 5653.2 5655.9 5702.9 3372.3 3932.4 4080.9 4090.0 4129.3 5073.5 5151.5 5728.7 5810.9 5842.4 3823.0 4153.6 4174.6 4180.7 4190.6 4001.0 4200.2 4241.0 4254.8 4269.3 4224.5 4411.4 4570.5 4580.2 4601.5 4786.5 5308.6 5636.5 5658.8 5671.1 1090.1 1164.6 1222.8 1236.6 1280.0 836.9 848.7 857.4 865.0 869.8 4104.2 4913.0 4926.7 4960.4 4970.4 4757.6 4919.5 4948.8 5412.5 5454.9 4357.6 4455.4 4589.6 4717.8 4828.3 2527.8 2840.8 2844.7 2855.5 2857.6 4370.6 4998.6 5323.5 5346.1 5594.6 4693.2 4935.9 5116.6 5524.4 5557.4 5111.4 5408.1 5567.4 5572.4 5575.5 4855.4 5332.8 5369.5 5407.3 5424.9 5108.3 5728.6 5755.2 5786.8 5815.8 5999.7 6013.8 6019.9 6032.7 6061.4 3169.9 3626.4 3820.6 3913.7 3957.4 5364.1 5774.0 5777.7 5807.8 5809.4 4979.4 5436.1 5446.5 5460.3 5663.0 4413.5 4999.7 5558.4 5599.5 5658.6 4802.4 5293.9 5294.5 5500.9 5563.9 4957.4 5040.4 5322.4 5630.5 5658.8 4792.1 5302.7 5511.9 5520.4 5534.2 05:33:39 INFO - PROCESS | 1639 | 05:33:39 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19872.47 Median:3866.20 stddev:28411.53 (734.9%) stddev-sans-first:28454.67 05:33:39 INFO - PROCESS | 1639 | Values: 674.8 703.5 741.6 829.0 844.7 15059.8 15666.3 15707.1 15712.6 15719.4 13614.5 13651.0 13688.9 13689.5 13711.9 575.9 669.3 835.2 850.0 865.1 677.7 858.0 862.8 872.9 874.7 923.1 924.8 925.6 926.1 927.0 910.7 921.2 923.0 925.8 930.0 522.7 534.1 534.3 537.7 538.7 14411.3 14928.0 14989.3 15282.5 15310.7 13872.9 14479.9 14508.6 14567.5 14794.6 11603.5 11608.5 11690.8 12039.6 12260.6 3711.2 3755.1 3789.8 3797.1 3935.3 110468.0 113798.0 114001.0 115751.0 116207.8 10787.0 10880.3 11002.7 11082.0 11294.3 21540.8 22372.4 22441.6 22575.0 22681.0 5942.4 7564.9 7608.3 7685.7 8199.8 4255.7 4293.7 4303.1 4310.4 4492.1 3603.7 3674.4 3685.5 3699.4 3705.8 616.5 633.3 664.6 665.6 685.9 663.5 684.2 697.6 703.4 734.7 8776.0 9748.9 10280.6 11019.2 11107.2 562.6 590.8 596.8 632.4 719.7 4760.9 5037.5 5039.5 5047.0 5051.2 94034.1 96666.0 98787.5 99913.5 100311.0 71487.6 71935.6 78980.0 79217.6 87563.0 71084.0 73028.0 73279.0 73411.6 73994.0 10579.2 10616.2 10774.2 10869.2 10880.9 42013.8 42116.8 43546.8 44010.1 45463.8 61472.0 62186.0 62448.7 64424.7 67771.0 12619.3 12806.3 12815.0 13235.0 13581.9 13414.6 14204.5 14454.0 14504.3 15035.8 11816.5 13010.0 13379.9 13598.4 14180.0 12775.1 13778.7 13940.7 14184.4 14197.6 12600.6 13065.5 14132.9 14168.6 14475.3 8338.6 11233.3 11601.1 11765.5 11836.6 9815.6 10588.0 11153.1 11499.9 11546.9 05:33:39 INFO - PROCESS | 1639 | -------- Summary: end -------- 05:33:39 INFO - PROCESS | 1639 | 05:33:40 INFO - TEST-INFO | 1639: exit 0 05:33:40 INFO - TEST-OK | dromaeo_css | took 1118929ms 05:33:40 INFO - TEST-START | kraken 05:33:40 INFO - operating with platform_type : mac_ 05:33:40 INFO - Initialising browser for kraken test... 05:33:40 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpQQXeh9/profile http://localhost:49247/getInfo.html) 05:33:42 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 05:33:42 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:33:42 INFO - PROCESS | 1647 | colorDepth:24 05:33:42 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:33:42 INFO - PROCESS | 1647 | __metrics 05:33:42 INFO - PROCESS | 1647 | 1485956022753 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:33:42 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:33:42 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:33:43 INFO - TEST-INFO | 1647: exit 0 05:33:43 INFO - Browser initialized. 05:33:43 INFO - Running cycle 1/1 for kraken test... 05:33:43 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpQQXeh9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:55 INFO - PROCESS | 1648 | RSS: Main: 228519936 05:33:55 INFO - PROCESS | 1648 | 05:33:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 05:33:59 INFO - PROCESS | 1648 | RSS: Main: 288636928 05:33:59 INFO - PROCESS | 1648 | 05:34:01 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 05:34:02 INFO - PROCESS | 1648 | RSS: Main: 292593664 05:34:02 INFO - PROCESS | 1648 | 05:34:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 05:34:06 INFO - PROCESS | 1648 | RSS: Main: 382861312 05:34:06 INFO - PROCESS | 1648 | 05:34:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 05:34:09 INFO - PROCESS | 1648 | RSS: Main: 285421568 05:34:09 INFO - PROCESS | 1648 | 05:34:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:34:11 INFO - PROCESS | 1648 | RSS: Main: 288055296 05:34:11 INFO - PROCESS | 1648 | 05:34:14 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 05:34:15 INFO - PROCESS | 1648 | RSS: Main: 331407360 05:34:15 INFO - PROCESS | 1648 | 05:34:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 05:34:18 INFO - PROCESS | 1648 | RSS: Main: 333492224 05:34:18 INFO - PROCESS | 1648 | 05:34:21 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 05:34:22 INFO - PROCESS | 1648 | RSS: Main: 328028160 05:34:22 INFO - PROCESS | 1648 | 05:34:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:34:24 INFO - PROCESS | 1648 | RSS: Main: 306683904 05:34:24 INFO - PROCESS | 1648 | 05:34:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:34:27 INFO - PROCESS | 1648 | RSS: Main: 310251520 05:34:27 INFO - PROCESS | 1648 | 05:34:29 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:34:29 INFO - PROCESS | 1648 | RSS: Main: 312848384 05:34:29 INFO - PROCESS | 1648 | 05:34:32 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:34:33 INFO - PROCESS | 1648 | RSS: Main: 331321344 05:34:33 INFO - PROCESS | 1648 | 05:34:36 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:34:37 INFO - PROCESS | 1648 | RSS: Main: 324669440 05:34:37 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 05:34:39 INFO - PROCESS | 1648 | RSS: Main: 370016256 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | __start_tp_report 05:34:39 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 05:34:39 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 05:34:39 INFO - PROCESS | 1648 | |i|pagename|runs| 05:34:39 INFO - PROCESS | 1648 | |0;ai-astar;110;114;129;123;113;114;114;113;113;113 05:34:39 INFO - PROCESS | 1648 | |1;audio-beat-detection;136;109;107;144;109;110;108;109;128;109 05:34:39 INFO - PROCESS | 1648 | |2;audio-dft;184;182;184;186;184;206;261;179;184;182 05:34:39 INFO - PROCESS | 1648 | |3;audio-fft;80;59;60;101;63;59;49;73;91;81 05:34:39 INFO - PROCESS | 1648 | |4;audio-oscillator;84;75;87;71;69;69;84;69;69;82 05:34:39 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;87;91;87;90;86;97;130;98;86 05:34:39 INFO - PROCESS | 1648 | |6;imaging-darkroom;95;95;95;106;144;103;94;95;94;94 05:34:39 INFO - PROCESS | 1648 | |7;imaging-desaturate;87;85;86;86;84;84;87;87;89;87 05:34:39 INFO - PROCESS | 1648 | |8;json-parse-financial;62;58;58;58;59;59;58;59;58;59 05:34:39 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;75;73;77;75;71;70;68;71;73 05:34:39 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;87;87;86;89;90;86;85;86;101;110 05:34:39 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;140;147;156;134;136;143;137;128;189;187 05:34:39 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;173;172;172;184;238;194;171;171;171;175 05:34:39 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;67;67;68;68;66;66;67;66;67 05:34:39 INFO - PROCESS | 1648 | __end_tp_report 05:34:39 INFO - PROCESS | 1648 | __start_cc_report 05:34:39 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2001 05:34:39 INFO - PROCESS | 1648 | __end_cc_report 05:34:39 INFO - PROCESS | 1648 | __startTimestamp1485956079302__endTimestamp 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | ------- Summary: start ------- 05:34:39 INFO - PROCESS | 1648 | Number of tests: 14 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.00 stddev:5.78 (5.1%) stddev-sans-first:5.76 05:34:39 INFO - PROCESS | 1648 | Values: 110.0 114.0 129.0 123.0 113.0 114.0 114.0 113.0 113.0 113.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:109.50 stddev:13.73 (12.5%) stddev-sans-first:12.71 05:34:39 INFO - PROCESS | 1648 | Values: 136.0 109.0 107.0 144.0 109.0 110.0 108.0 109.0 128.0 109.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:193.20 Median:184.00 stddev:24.95 (13.6%) stddev-sans-first:26.24 05:34:39 INFO - PROCESS | 1648 | Values: 184.0 182.0 184.0 186.0 184.0 206.0 261.0 179.0 184.0 182.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:71.60 Median:68.00 stddev:16.46 (24.2%) stddev-sans-first:17.18 05:34:39 INFO - PROCESS | 1648 | Values: 80.0 59.0 60.0 101.0 63.0 59.0 49.0 73.0 91.0 81.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:78.50 stddev:7.50 (9.6%) stddev-sans-first:7.37 05:34:39 INFO - PROCESS | 1648 | Values: 84.0 75.0 87.0 71.0 69.0 69.0 84.0 69.0 69.0 82.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:90.00 stddev:13.28 (14.8%) stddev-sans-first:14.00 05:34:39 INFO - PROCESS | 1648 | Values: 90.0 87.0 91.0 87.0 90.0 86.0 97.0 130.0 98.0 86.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:101.50 Median:94.50 stddev:15.52 (16.4%) stddev-sans-first:16.28 05:34:39 INFO - PROCESS | 1648 | Values: 95.0 95.0 95.0 106.0 144.0 103.0 94.0 95.0 94.0 94.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:87.00 stddev:1.55 (1.8%) stddev-sans-first:1.62 05:34:39 INFO - PROCESS | 1648 | Values: 87.0 85.0 86.0 86.0 84.0 84.0 87.0 87.0 89.0 87.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:59.00 stddev:1.23 (2.1%) stddev-sans-first:0.53 05:34:39 INFO - PROCESS | 1648 | Values: 62.0 58.0 58.0 58.0 59.0 59.0 58.0 59.0 58.0 59.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:73.00 stddev:3.58 (4.9%) stddev-sans-first:2.83 05:34:39 INFO - PROCESS | 1648 | Values: 65.0 75.0 73.0 77.0 75.0 71.0 70.0 68.0 71.0 73.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:86.50 stddev:8.22 (9.5%) stddev-sans-first:8.61 05:34:39 INFO - PROCESS | 1648 | Values: 87.0 87.0 86.0 89.0 90.0 86.0 85.0 86.0 101.0 110.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:145.00 stddev:21.57 (14.9%) stddev-sans-first:22.59 05:34:39 INFO - PROCESS | 1648 | Values: 140.0 147.0 156.0 134.0 136.0 143.0 137.0 128.0 189.0 187.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:174.00 stddev:21.02 (12.1%) stddev-sans-first:22.04 05:34:39 INFO - PROCESS | 1648 | Values: 173.0 172.0 172.0 184.0 238.0 194.0 171.0 171.0 171.0 175.0 05:34:39 INFO - PROCESS | 1648 | 05:34:39 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 05:34:39 INFO - PROCESS | 1648 | Values: 67.0 67.0 67.0 68.0 68.0 66.0 66.0 67.0 66.0 67.0 05:34:39 INFO - PROCESS | 1648 | -------- Summary: end -------- 05:34:39 INFO - PROCESS | 1648 | 05:34:40 INFO - TEST-INFO | 1648: exit 0 05:34:40 INFO - TEST-OK | kraken | took 59954ms 05:34:40 INFO - SUITE-END | took 1178s 05:34:40 INFO - Completed test suite (00:19:38) 05:34:40 INFO - javascript benchmark 05:34:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7255.8154210221255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.137544238498, 3328.9001329960092, 3342.181165614425, 3346.7657263991523, 3386.05190546647, 5603.859903502414, 5613.101903695405, 5664.291963504556, 5670.064439367499, 5681.772729090837, 5664.150377443386, 5707.914381284284, 5783.826485205451, 5793.275840519935, 5825.5048320892665, 5136.383633963929, 5156.948430515703, 5190.013897359489, 5253.238280449333, 5290.497402746733, 5310.8406747797435, 5312.840614781543, 5323.840284791443, 5329.546988505962, 5375.731213439332, 5136.332276804029, 5295.179247216687, 5321.6540924839765, 5324.1215199492035, 5350.3312085989255, 4337.175936572041, 5159.845204643867, 5175.96480703857, 5230.790768369298, 5239.842804715865, 5184.611154163415, 5189.2475591039465, 5201.297824793643, 5222.2427747976335, 5257.13257312543, 4855.465898751135, 5702.230198923134, 5721.856953576153, 5726.3700992890745, 5762.250907382056, 6117.553376797881, 6183.041628547582, 6192.071189321638, 6243.1883855099, 6246.718897649616, 4960.8759781005765, 5432.972835135839, 6293.653849038301, 6310.5898116622275, 6330.145430366888, 5814.302283725895, 5860.853478663067, 5872.765089396419, 5874.0, 5890.587658863924, 6215.81352559424, 6241.21984751906, 6245.687715614201, 6284.340144284875, 6299.433051025429, 6136.274005701014, 6167.907481387782, 6191.102290167901, 6201.1938448002375, 6206.627602343874, 6112.329598297095, 6229.875402491924, 6243.687815609202, 6244.1570387997945, 6250.96859018266, 6127.250613123855, 6141.792871882774, 6145.907811382833, 6183.690815459209, 6217.9689101555105, 6212.0, 6214.627122372672, 6216.471599914058, 6224.688765561795, 6224.941759900737, 4976.900461990812, 4996.150654388763, 4997.925031124463, 5000.549950504472, 5019.447860735316, 4408.4930233023615, 4431.423914891044, 4434.600885920218, 4476.216662084123, 4517.774111294488, 5003.0, 5722.456366645162, 5802.071668533099, 5815.244018277597, 5853.9414605854245, 6159.5688301818445, 6203.31763506014, 6223.471004964629, 6225.968870155621, 6298.622082675054, 5588.636738611977, 5675.886482270332, 5805.983952808242, 5806.303243610793, 5814.854628634318, 5412.621116521885, 5419.376771671229, 5447.210154527651, 5447.754851031713, 5517.503424691797, 6254.6247225166635, 6290.370962903673, 6300.2754683212015, 6302.432781049728, 6312.936870631235, 6133.877322453437, 6303.621782693053, 6306.936930630635, 6316.7473301069285, 6325.873482530232, 6217.533684973736, 6218.844528886814, 6239.594426362272, 6287.811365659038, 6299.622022678654, 6242.438180563771, 6243.063540468785, 6249.437550620466, 6252.437280644764, 6269.216347956505, 6143.139960405458, 6275.309715931336, 6286.622802631857, 6305.684715764285, 6314.6842657866, 5982.940170598238, 6044.002739547926, 6051.12258722474, 6106.358832322542, 6113.0, 6049.939500605114, 6066.7269972850445, 6076.422739839708, 6092.543059270661, 6099.359567245376, 5931.9406805931385, 5941.851453713692, 5952.553558483044, 5956.8808623828145, 5965.642061476326, 6114.510839132948, 6117.816465506042, 6120.204373431426, 6135.969320153371, 6139.0, 5927.407259274038, 5930.703464826655, 5936.821895343147, 5938.287405511366, 5939.673317967553], "value": 5695.6399569542555, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.25732372146894, 501.1280372152455, 506.2077848167615, 512.5284738041001, 542.9185622156676, 38366.424503632465, 39957.20085598286, 40223.396649050286, 40261.798691006574, 41949.0, 40845.59154408455, 41173.38239926396, 41256.0, 41260.58739412612, 41427.0, 8408.285295749869, 8634.654613815439, 9204.769880752985, 9405.670801521948, 9420.293477989144, 11050.834237486444, 11369.829452558219, 11371.431428428587, 11552.942235288812, 11583.073354131691, 11400.771984560304, 11458.427078646077, 11486.425678716072, 11493.367864767435, 11522.308661480296, 8437.0, 8640.524771137585, 8640.697575584825, 11360.091192704564, 11447.141464390163, 8490.957545212295, 11054.557817687282, 11314.37770922599, 11364.374959377232, 11553.942230288794, 39104.21791564153, 39152.41271380903, 39180.0, 39272.607273927184, 39861.81796366483, 4003.3594624860175, 4004.6796256299426, 4030.8186131624147, 4056.71602987791, 4168.7081904266715, 25005.874970625213, 29435.11694649164, 30431.0, 30528.694713052806, 30546.389072218655, 3118.953215701766, 3127.53087036944, 3207.134073800084, 3276.836158192105, 3284.0476261884023, 469738.99999999657, 470433.0, 470747.0, 471674.0, 472791.0, 59557.702211488664, 59936.50158746066, 59936.70031649901, 60001.79996400048, 60042.69978650166, 63472.68263658652, 63562.0, 63677.72644547176, 63678.36321636817, 63752.68123659352, 13778.793318100237, 15502.837287203505, 15974.281157347947, 15988.600284992968, 16047.0, 11129.554817807359, 11393.886061139448, 11401.144914131473, 11556.017738492154, 12358.196717213352, 13648.454061837416, 14872.925635371754, 14952.850471495145, 16683.582910427336, 16684.6663066738, 5613.915791263053, 5718.913406452761, 5838.620489668158, 5967.19442875215, 6834.188939930922, 858.8319884956579, 862.2756884217288, 888.7289376740027, 888.7911340834899, 901.5312037740339, 29302.70697293, 29507.0, 29644.703552964627, 30078.54882176769, 30377.0, 1013.8543445906079, 1038.408107378802, 1090.0843291635065, 1093.7375029992745, 1149.6378640728144, 13327.866721332857, 14407.78388324176, 15163.119007388561, 15287.541373758804, 15469.381224750883, 579865.0, 580679.0, 581107.0, 583142.0, 584924.0, 58731.119033214534, 59142.0, 59150.70424647849, 59154.70422647859, 59232.70383648054, 1254.44177341086, 1375.2504884837788, 1392.693607406369, 1405.4729476446332, 1420.8081908942368, 28551.857240714493, 28753.137405877857, 28859.42281154407, 29602.111936641933, 29734.85132574323, 49522.0, 49875.75062124666, 49879.0, 49931.251031233835, 49936.0, 482537.0, 486275.0, 486605.0, 487128.0, 487796.0, 38608.0, 39083.0, 39366.0, 39415.40876886906, 39625.40561891632, 37143.25713485769, 43002.0, 44053.0, 45807.0, 46596.53403465922, 38322.616773831905, 38368.65709700147, 38661.6133838658, 39243.0, 40036.59963400329, 37529.4370584436, 37964.62035379611, 38511.80744096261, 38550.42174367443, 39167.608323917535, 36814.8159259202, 37129.62870371262, 37393.813030934674, 37764.0, 37950.0, 32713.0, 33531.66468335383, 34329.48505772366, 34358.48462273018, 34497.0, 24691.0, 24965.12622058292, 24993.50012999766, 25026.62460063064, 25125.012451438804], "value": 19832.253376769433, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.46141251173106, 366.15783697495755, 395.1661993194359, 403.4976454314379, 430.24921511961634, 12138.0, 15526.223688815557, 17423.73864392032, 17616.65283596116, 21000.84495352755, 20830.687539686878, 21674.674879876813, 21999.560008799814, 22061.889690551525, 22572.88713556434, 4999.225120106371, 5733.856653583663, 5943.167956486095, 5977.6114552553945, 5978.372270911557, 4633.8841528961875, 5864.208331875185, 5874.383189765077, 5877.059670452729, 5881.117832325164, 5626.465485778865, 5665.3768085510565, 5760.884782304352, 5836.15375770513, 5844.736986835582, 5775.1337299405, 5796.188533605298, 5815.592908496407, 5817.447342502455, 5851.15158302046, 4428.22506061438, 4914.655974081816, 5085.211792172219, 5121.4110377306715, 5156.226566015091, 25980.090696825522, 27119.593206101923, 27181.320466988287, 27242.182734517995, 27423.45153096927, 2697.7167397423186, 2746.835189888613, 2755.118362124121, 2809.1712944681367, 2826.4771017361854, 7770.611469426506, 7952.181662024858, 15927.840721592751, 16016.4394246202, 16301.836981630151, 1687.8987260764393, 1713.8971661700339, 1795.9852683234062, 1816.946171220689, 1822.6992546229856, 126957.0, 130628.0, 131025.0, 132055.0, 133034.6696533032, 8385.119562445978, 10613.734656633645, 11296.265742726697, 11404.657860264206, 11539.826902596467, 14855.2572371381, 14874.32262192794, 14882.139732533433, 14884.627884302763, 14904.9566530344, 7243.203247642755, 8654.35092368075, 8676.123518743969, 8684.261837743863, 8800.735977920673, 7009.509334346649, 8413.579321033923, 8512.70205542803, 8681.0, 8709.520976346235, 7070.292970702889, 7074.257202993715, 7083.220845706968, 7187.676554555058, 7238.059052323164, 1713.3660545597959, 1831.1485159400943, 1938.951526211856, 1939.679952807799, 1948.3083505355678, 1052.7210289273348, 1060.0, 1070.2240875365453, 1073.511552243743, 1114.420501339299, 17200.655986880192, 22538.0, 22743.09027638909, 23055.88472057629, 23385.41536461568, 1077.7197928538637, 1085.3270971997413, 1114.6154576671036, 1118.8601424821898, 1123.0790751583622, 11181.44092795357, 11824.52701891932, 12119.454624541917, 12131.0, 12198.817017744741, 709027.0, 710367.0, 718530.0, 728293.0, 729552.0, 91892.54053729822, 93468.59797103048, 93939.0, 122118.0, 128414.0, 995.9053889880449, 996.8953259907895, 1037.061459379263, 1037.444966942682, 1037.527924794217, 19627.509312267306, 19628.803711963268, 19738.210471580976, 19998.0, 20052.0, 35026.67246611568, 43949.901252468946, 49885.501144988084, 54838.725806372306, 55523.0, 74820.25179748132, 75234.6238268827, 76401.23598764163, 76652.61673691595, 78760.21239787758, 8101.8379632408205, 8143.714969976022, 8292.502449853027, 8335.708250211213, 8582.757674754932, 13448.394822232827, 13484.460621575026, 13714.0, 13908.0, 13911.373988170759, 11171.329720216812, 11464.426778660867, 11672.299662020307, 11837.585684501, 11840.1711880171, 10034.297599167989, 10629.840552391566, 10651.147908167173, 10654.45510401003, 11626.302421854716, 9940.751481213027, 11445.713857153638, 11772.117091218022, 12010.879891200977, 12058.733832947764, 8051.953246077972, 8145.5486697032675, 8377.45546539473, 8519.531425771642, 8553.187447192508, 10485.318454300446, 11372.658820235407, 11398.658040258806, 11404.48679809394, 11894.34581098048], "value": 9878.79112099257, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.548550749991, 1412.6468382904275, 1424.7150569886023, 1432.6991331820318, 1441.3946142620098, 7433.033705618269, 7768.378529717623, 7768.417368697342, 7768.883466747996, 7776.533407995525, 8052.315553177972, 8095.06906705729, 8142.7557173284895, 8204.95897520513, 8235.752927412172, 2247.292102987562, 2291.4385975436044, 2292.197730794217, 2292.931212063641, 2296.4144143243448, 2039.3066357438468, 2232.743234528026, 2288.965665515019, 2301.2750983440246, 2304.5966955782756, 2050.7231523744254, 2080.646290130674, 2200.7909248621354, 2270.863748175115, 2277.237125562938, 1765.5233087066483, 2085.8018488243742, 2121.3635909227323, 2123.288698286076, 2127.8084972352563, 1655.5778276539406, 2087.676410156428, 2236.9664455033026, 2296.9425764355856, 2297.356740112771, 7018.298170182992, 7629.961850190769, 8130.55281959492, 8312.41813073079, 8323.66705331786, 5020.3724534433195, 5180.222966555009, 5180.766865491024, 5182.378114626231, 5187.896242075138, 3495.0, 3497.1082373994723, 3497.91255218619, 3578.409562422223, 3578.8568457261676, 1057.349729916096, 1225.91418600698, 1229.6372570091844, 1270.646761199329, 1275.7193417448148, 10703.090237329836, 10786.244962852603, 10797.028267455888, 10806.35161890289, 10850.782984340269, 4301.268784306675, 4584.724916505021, 4649.674522783373, 4668.5564871337165, 4677.836275730401, 9329.580168892417, 9366.391184572982, 9371.203447706885, 9377.624895004259, 9395.154436100784, 5198.324217851655, 5206.219067139959, 5210.713410762444, 5213.165893457029, 5214.191800270888, 4511.661370585743, 4673.158831410311, 4710.976445117821, 4763.95236047642, 4765.809367625326, 3753.512043434336, 3790.488284081669, 3795.7153213509096, 3840.6543411093135, 3869.8839034829, 1202.9053561259386, 1253.5612535612645, 1257.993710031444, 1258.7860063789178, 1274.7514234724326, 794.8728203487415, 842.5029232752705, 880.471716969813, 921.1985572551789, 926.4672813132476, 5001.7499125043605, 5722.341930677942, 5813.0, 5989.161517387569, 6126.356732543108, 889.2707979456783, 966.4877614864133, 994.9353292035994, 1001.9348742331725, 1026.0509029148009, 3327.3678001179696, 3661.761985470989, 3850.7689538627774, 3907.941380879289, 3935.685145188378, 9006.50464224474, 9409.90590094104, 9443.150116489549, 9696.466694331739, 9894.356866803635, 9666.95166524177, 9670.371425857296, 9778.168855647207, 9984.700458986243, 9986.900130998742, 7667.079950405987, 7816.804579885548, 7829.921700783033, 7837.333826624801, 7841.921580784119, 4385.956140438555, 5703.173039909107, 5731.7420716067045, 5831.650100993954, 5924.4667979882015, 8589.65641374338, 8921.375503714678, 8930.19628233462, 9028.187463128088, 9124.817503650023, 9113.407628504126, 9267.304952128752, 9268.073192680678, 9363.719088427359, 9381.81236375255, 7061.964690176516, 7185.892211616934, 7196.820079498055, 7209.315115064061, 7216.3505284524645, 7597.582132982739, 7734.6132693366235, 7812.179721129427, 7842.176571459914, 7849.01887264092, 6990.056342393712, 7010.649467526501, 7038.331358520933, 7040.577565346096, 7210.927890721236, 6918.169819621678, 6931.93068069333, 6971.8257043572285, 7034.120734908137, 7053.400460960671, 6096.847578810565, 6292.968535157295, 6353.745850165941, 6424.164858568543, 6432.485401167989, 4371.781410929504, 4899.069176856386, 4993.6254780892305, 5026.472220416804, 5030.773615187399, 4953.108440480748, 5051.676228934732, 5052.621053421081, 5183.429822719573, 5216.843494695166], "value": 4357.472864225251, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.3115225167248, 329.85321531918294, 361.1765175400087, 370.2373111390535, 374.0797637810985, 4722.340186355576, 5015.5486006259425, 5388.622796404248, 5404.621676482648, 5475.917861232075, 4074.9592504075026, 4364.32352985287, 4893.923336865883, 5128.53843154117, 5273.815416460425, 4119.382092686091, 4155.833766649331, 4179.143275628504, 4213.682474638952, 4238.449001629784, 3709.5733990591025, 4176.540580536153, 4214.431051807997, 4221.7889105544755, 4278.465191851019, 3631.2737452509473, 3691.446283057536, 3891.385075193791, 4161.854335098272, 4170.8540201092965, 4157.334826427757, 4158.7296825706235, 4163.980469472303, 4168.6665066794885, 4171.478565179353, 3681.895431370605, 3690.2435000824767, 3694.538182727159, 3695.5565332160045, 3711.832967516468, 4800.175823297598, 4962.429320628138, 5024.96988117435, 5195.558377537914, 5255.505089115772, 4283.7429754214845, 4491.752953587551, 4565.885852853705, 4824.079438972264, 4856.587190088847, 4953.23224900141, 5617.365673319487, 5653.236813030229, 5655.8586035349035, 5702.857428564277, 3372.2749608833974, 3932.390479475686, 4080.8575497863562, 4090.0, 4129.256733787917, 5073.524635624609, 5151.5106064923775, 5728.656280623134, 5810.941890581083, 5842.357340692521, 3822.9869084692577, 4153.563875793058, 4174.603412675791, 4180.728252663567, 4190.575204430447, 4000.979995100006, 4200.180964711852, 4241.0, 4254.808533615995, 4269.270945267172, 4224.45082139326, 4411.360352748833, 4570.451545814523, 4580.244259697112, 4601.5168407317415, 4786.497417771154, 5308.628396012242, 5636.520895723909, 5658.830235092953, 5671.092625180034, 1090.1333439915286, 1164.6273192578462, 1222.7554489102038, 1236.6166488388628, 1279.9936000320124, 836.9412692943431, 848.7099608595024, 857.4298091769028, 865.0009239328585, 869.8477766390856, 4104.15864747726, 4913.010230856509, 4926.679765815211, 4960.429550601727, 4970.365706175733, 4757.573637444928, 4919.508049195123, 4948.777305021283, 5412.484504338791, 5454.945450545523, 4357.607815296575, 4455.420795296591, 4589.632829373642, 4717.820544863784, 4828.272026305126, 2527.8356906801, 2840.829550226993, 2844.6586409630977, 2855.528837741791, 2857.5999360089604, 4370.6066454018655, 4998.625652814884, 5323.494268044529, 5346.064438723208, 5594.608377413542, 4693.22561777303, 4935.901281974412, 5116.623028800974, 5524.364698059694, 5557.444255574491, 5111.412187598399, 5408.107662235766, 5567.415421380859, 5572.3870374258795, 5575.498205161555, 4855.417349917891, 5332.82677810881, 5369.5167434931045, 5407.270018547603, 5424.864378390572, 5108.284840122312, 5728.598998070095, 5755.165501002248, 5786.797462088975, 5815.82552523425, 5999.740054588411, 6013.849653758516, 6019.939800601938, 6032.728527216198, 6061.393860613904, 3169.904902852918, 3626.437902125175, 3820.6179382061573, 3913.706472014553, 3957.406389041667, 5364.088105022233, 5773.971130144322, 5777.740001700017, 5807.82576522705, 5809.448102430262, 4979.402471703419, 5436.091690945325, 5446.482584154499, 5460.31746031746, 5662.971685141548, 4413.492448368479, 4999.700077979679, 5558.44415558441, 5599.488138202744, 5658.632188907873, 4802.447718512416, 5293.894122117613, 5294.523492885659, 5500.944990550043, 5563.944360556505, 4957.35554377943, 5040.395152581714, 5322.361316642027, 5630.549556035426, 5658.801941932013, 4792.065547218259, 5302.70835104073, 5511.889762204653, 5520.3990842655185, 5534.225208470737], "value": 4054.136337641337, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.7672053141664, 703.5145749432891, 741.5698894641108, 828.9585520723962, 844.7127976487994, 15059.774103388445, 15666.295016724247, 15707.136107514068, 15712.607184820385, 15719.37122515101, 13614.455421783117, 13651.044426890121, 13688.863111368908, 13689.520866769664, 13711.862881371184, 575.9078547432412, 669.3172963577149, 835.1773503099442, 849.9532525711099, 865.0786911938796, 677.7085853083172, 857.9742607721747, 862.7972426479772, 872.9345299102561, 874.6982291109589, 923.0907556057278, 924.7549399409162, 925.6436272035281, 926.0924294191697, 927.0451435021938, 910.658503061352, 921.1755478846395, 923.0354279777648, 925.7870689741397, 929.953502324881, 522.7412430846717, 534.0653855752433, 534.3480953237045, 537.7311344327836, 538.6552606331951, 14411.279436028157, 14928.0, 14989.325480353411, 15282.541523754306, 15310.693786124326, 13872.930635346758, 14479.855201447956, 14508.564743057725, 14567.490137845125, 14794.630134246623, 11603.477843497063, 11608.477618507188, 11690.766184676344, 12039.578614748441, 12260.570880019246, 3711.183539621279, 3755.0800053986677, 3789.77261364319, 3797.0697179191275, 3935.330993731073, 110468.0, 113798.0, 114001.0, 115751.0, 116207.83792162055, 10787.029167374974, 10880.347179169275, 11002.669919902415, 11081.986166204913, 11294.265872718248, 21540.78459215388, 22372.440688982904, 22441.55116897653, 22575.0, 22681.0, 5942.405759424023, 7564.886526702104, 7608.31525162738, 7685.692572297157, 8199.7950051248, 4255.722146901625, 4293.720908140961, 4303.07483890965, 4310.396544483775, 4492.0791237796175, 3603.657652523006, 3674.356987527172, 3685.539307586552, 3699.408094704834, 3705.7591256568235, 616.4729156571157, 633.2938773267773, 664.6012392564422, 665.6272487407067, 685.9382655561024, 663.5123184459419, 684.1960696181966, 697.5988806436319, 703.3880523944201, 734.7281505842873, 8776.0, 9748.902510974942, 10280.61211736421, 11019.173561982883, 11107.24545481645, 562.5893098038455, 590.8463799412098, 596.8358701357075, 632.4055387935326, 719.7408932784193, 4760.857174284777, 5037.496250374933, 5039.496050394931, 5047.0, 5051.217061355495, 94034.05965940253, 96666.0, 98787.50606246923, 99913.50043249737, 100310.99689003016, 71487.64256178685, 71935.64032179806, 78980.0, 79217.60391198007, 87563.0, 71084.0, 73028.0, 73279.0, 73411.63294183495, 73994.0, 10579.15366770641, 10616.203784716332, 10774.24580279373, 10869.239153259196, 10880.945595271973, 42013.78993105015, 42116.78941605272, 43546.822658866295, 44010.11979760451, 45463.77268113638, 61472.0, 62186.0, 62448.687756560925, 64424.677876610316, 67771.0, 12619.305938173122, 12806.335176427378, 12815.0, 13235.0, 13581.932090339486, 13414.597562073153, 14204.48588986468, 14454.0, 14504.274786260856, 15035.849641503883, 11816.468258928197, 13010.0, 13379.86620133825, 13598.388072536562, 14180.0, 12775.105742597929, 13778.724425511635, 13940.721185576434, 14184.36170372315, 14197.574072777834, 12600.55898043564, 13065.542706005244, 14132.929335353258, 14168.574942751733, 14475.27623618836, 8338.583070846313, 11233.269837460539, 11601.12991525622, 11765.52937882475, 11836.644900652995, 9815.607375705178, 10588.047395162574, 11153.107751379708, 11499.942500287445, 11546.94226528862], "value": 7401.461539487864, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1475.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 129.0, 123.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 109.0, 107.0, 144.0, 109.0, 110.0, 108.0, 109.0, 128.0, 109.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 184.0, 186.0, 184.0, 206.0, 261.0, 179.0, 184.0, 182.0], "value": 193.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 101.0, 63.0, 59.0, 49.0, 73.0, 91.0, 81.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 87.0, 71.0, 69.0, 69.0, 84.0, 69.0, 69.0, 82.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 87.0, 90.0, 86.0, 97.0, 130.0, 98.0, 86.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 106.0, 144.0, 103.0, 94.0, 95.0, 94.0, 94.0], "value": 101.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 86.0, 84.0, 84.0, 87.0, 87.0, 89.0, 87.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 58.0, 58.0, 59.0, 59.0, 58.0, 59.0, 58.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 73.0, 77.0, 75.0, 71.0, 70.0, 68.0, 71.0, 73.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 89.0, 90.0, 86.0, 85.0, 86.0, 101.0, 110.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 147.0, 156.0, 134.0, 136.0, 143.0, 137.0, 128.0, 189.0, 187.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 172.0, 184.0, 238.0, 194.0, 171.0, 171.0, 171.0, 175.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 68.0, 66.0, 66.0, 67.0, 66.0, 67.0], "value": 66.9, "unit": "score"}]}]} 05:34:40 INFO - Return code: 0 05:34:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:34:40 ERROR - # TBPL SUCCESS # 05:34:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:34:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:34:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:34:40 INFO - 2017-02-01 05:34:40.644 screenresolution[1652:10634] starting screenresolution argv=screenresolution get 05:34:40 INFO - 2017-02-01 05:34:40.646 screenresolution[1652:10634] Display 0: 1600x1200x32@60 05:34:40 INFO - 2017-02-01 05:34:40.653 screenresolution[1653:10637] starting screenresolution argv=screenresolution list 05:34:40 INFO - Available Modes on Display 0 05:34:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:34:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:34:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:34:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:34:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:34:40 INFO - Intel Iris: 05:34:40 INFO - Chipset Model: Intel Iris 05:34:40 INFO - Type: GPU 05:34:40 INFO - Bus: Built-In 05:34:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:34:40 INFO - Vendor: Intel (0x8086) 05:34:40 INFO - Device ID: 0x0a2e 05:34:40 INFO - Revision ID: 0x0009 05:34:40 INFO - Displays: 05:34:40 INFO - Display: 05:34:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:34:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:34:40 INFO - Display Serial Number: 1600x1200 60 05:34:40 INFO - Main Display: Yes 05:34:40 INFO - Mirror: Off 05:34:40 INFO - Online: Yes 05:34:40 INFO - Rotation: Supported 05:34:40 INFO - Return code: 0 05:34:40 INFO - Running post-action listener: _resource_record_post_action 05:34:40 INFO - [mozharness: 2017-02-01 13:34:40.898023Z] Finished run-tests step (success) 05:34:40 INFO - Running post-run listener: _resource_record_post_run 05:34:40 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32533504; Write bytes: 556780032; Read time: 201; Write time: 1678 05:34:40 INFO - TinderboxPrint: CPU usage
53.6% 05:34:40 INFO - TinderboxPrint: I/O read bytes / time
32,533,504 / 201 05:34:40 INFO - TinderboxPrint: I/O write bytes / time
556,780,032 / 1,678 05:34:40 INFO - TinderboxPrint: CPU idle
2,231.2 (46.4%) 05:34:40 INFO - TinderboxPrint: CPU system
962.2 (20.0%) 05:34:40 INFO - TinderboxPrint: CPU user
1,616.4 (33.6%) 05:34:40 INFO - TinderboxPrint: Swap in / out
281,186,304 / 0 05:34:40 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 240306688; Write bytes: 234378752; Read time: 17564; Write time: 507 05:34:40 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30747648; Write bytes: 301032448; Read time: 187; Write time: 1114 05:34:41 INFO - Running post-run listener: _upload_blobber_files 05:34:41 INFO - Blob upload gear active. 05:34:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:34:41 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 05:34:41 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'] 05:34:41 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 05:34:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:34:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:34:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:34:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:42 INFO - (blobuploader) - INFO - Done attempting. 05:34:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:34:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:34:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:43 INFO - (blobuploader) - INFO - Done attempting. 05:34:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:34:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:34:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:44 INFO - (blobuploader) - INFO - Done attempting. 05:34:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:34:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:34:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:34:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:34:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:34:47 INFO - (blobuploader) - INFO - Done attempting. 05:34:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:34:47 INFO - Return code: 0 05:34:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:34:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:34:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c7e7d9f020c4157bf9d9a10a3e2772b42e7d9224cfcf87014b7aebd65a8b6f85364ea374d164b25ac026bbd3b4721feb3b1573e6319aab99f38e3a8945f8cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6c8c9922d2b678afa3e7b777b6aeb06442e44a17171eafb39dcf1173a6aabe2b71302a197f395ef790e0adb15243541a19e60beecd3ee6e3e1c5712319485e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a7f32023579cb56523f320239648a366ee13276862ffdc5326e80f26e5be5f24da54ef93b99f0cf75fc917321552e543c41776e2d05567476faa8e80471471", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e575c5a8903e48282e64e8069975aa964ca4beefdc2e08789a886701c91d1c06a500add325d08d0355cab3ddd3f0b1d4b1f08aa0193cf929c6a8cef74fe8799"} 05:34:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:34:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:34:47 INFO - Contents: 05:34:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c7e7d9f020c4157bf9d9a10a3e2772b42e7d9224cfcf87014b7aebd65a8b6f85364ea374d164b25ac026bbd3b4721feb3b1573e6319aab99f38e3a8945f8cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6c8c9922d2b678afa3e7b777b6aeb06442e44a17171eafb39dcf1173a6aabe2b71302a197f395ef790e0adb15243541a19e60beecd3ee6e3e1c5712319485e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a7f32023579cb56523f320239648a366ee13276862ffdc5326e80f26e5be5f24da54ef93b99f0cf75fc917321552e543c41776e2d05567476faa8e80471471", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e575c5a8903e48282e64e8069975aa964ca4beefdc2e08789a886701c91d1c06a500add325d08d0355cab3ddd3f0b1d4b1f08aa0193cf929c6a8cef74fe8799"} 05:34:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:34:47 INFO - Copying logs to upload dir... 05:34:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:34:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.290928 ========= master_lag: 0.65 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-01 05:34:47.737163) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 05:34:47.740281) ========= 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.wAQfVCPO7h/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.LpWDYo9B63/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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/5c7e7d9f020c4157bf9d9a10a3e2772b42e7d9224cfcf87014b7aebd65a8b6f85364ea374d164b25ac026bbd3b4721feb3b1573e6319aab99f38e3a8945f8cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6c8c9922d2b678afa3e7b777b6aeb06442e44a17171eafb39dcf1173a6aabe2b71302a197f395ef790e0adb15243541a19e60beecd3ee6e3e1c5712319485e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a7f32023579cb56523f320239648a366ee13276862ffdc5326e80f26e5be5f24da54ef93b99f0cf75fc917321552e543c41776e2d05567476faa8e80471471", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e575c5a8903e48282e64e8069975aa964ca4beefdc2e08789a886701c91d1c06a500add325d08d0355cab3ddd3f0b1d4b1f08aa0193cf929c6a8cef74fe8799"} build_url:https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011090 build_url: 'https://queue.taskcluster.net/v1/task/F0lGuts5S7-sUIujVINDeA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c7e7d9f020c4157bf9d9a10a3e2772b42e7d9224cfcf87014b7aebd65a8b6f85364ea374d164b25ac026bbd3b4721feb3b1573e6319aab99f38e3a8945f8cb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6c8c9922d2b678afa3e7b777b6aeb06442e44a17171eafb39dcf1173a6aabe2b71302a197f395ef790e0adb15243541a19e60beecd3ee6e3e1c5712319485e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2a7f32023579cb56523f320239648a366ee13276862ffdc5326e80f26e5be5f24da54ef93b99f0cf75fc917321552e543c41776e2d05567476faa8e80471471", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e575c5a8903e48282e64e8069975aa964ca4beefdc2e08789a886701c91d1c06a500add325d08d0355cab3ddd3f0b1d4b1f08aa0193cf929c6a8cef74fe8799"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 05:34:47.775552) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 05:34:47.775893) ========= 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.wAQfVCPO7h/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.LpWDYo9B63/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.004987 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 05:34:47.824694) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 05:34:47.825022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 05:34:47.825329) ========= ========= Total master_lag: 0.89 =========