builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0348 starttime: 1485864935.05 results: success (0) buildid: 20170131030205 builduid: 98f0121b729d47b1bfa7e25b44eb052a revision: 9c06e744b1befb3a2e2fdac7414ce18220774a1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.050997) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.051360) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.051646) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.143882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.144196) ========= 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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-31 04:15:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2017-01-31 04:15:35 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.585407 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.753737) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.754030) ========= 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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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.036328 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.812292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:35.812619) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9c06e744b1befb3a2e2fdac7414ce18220774a1d --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 9c06e744b1befb3a2e2fdac7414ce18220774a1d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kKSxfjkcjg/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.QlVBBgHnAU/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-31 04:15:35,920 truncating revision to first 12 chars 2017-01-31 04:15:35,920 Setting DEBUG logging. 2017-01-31 04:15:35,920 attempt 1/10 2017-01-31 04:15:35,920 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9c06e744b1be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 04:15:36,607 unpacking tar archive at: mozilla-central-9c06e744b1be/testing/mozharness/ program finished with exit code 0 elapsedTime=0.960048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:36.797361) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:36.797635) ========= script_repo_revision: 9c06e744b1befb3a2e2fdac7414ce18220774a1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:36.798008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:36.798271) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 04:15:36.818144) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-31 04:15:36.818583) ========= /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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:15:37 INFO - MultiFileLogger online at 20170131 04:15:37 in /builds/slave/test 04:15:37 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 04:15:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:15:37 INFO - {'append_to_log': False, 04:15:37 INFO - 'base_work_dir': '/builds/slave/test', 04:15:37 INFO - 'blob_upload_branch': 'Firefox', 04:15:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:15:37 INFO - 'branch': 'Firefox', 04:15:37 INFO - 'buildbot_json_path': 'buildprops.json', 04:15:37 INFO - 'config_files': ('talos/mac_config.py',), 04:15:37 INFO - 'default_actions': ('clobber', 04:15:37 INFO - 'read-buildbot-config', 04:15:37 INFO - 'download-and-extract', 04:15:37 INFO - 'populate-webroot', 04:15:37 INFO - 'create-virtualenv', 04:15:37 INFO - 'install', 04:15:37 INFO - 'run-tests'), 04:15:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:15:37 INFO - 'download_minidump_stackwalk': True, 04:15:37 INFO - 'download_symbols': 'ondemand', 04:15:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:15:37 INFO - 'tooltool.py': '/tools/tooltool.py', 04:15:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:15:37 INFO - '/tools/misc-python/virtualenv.py')}, 04:15:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:15:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:15:37 INFO - 'installer_path': 'installer.exe', 04:15:37 INFO - 'log_level': 'info', 04:15:37 INFO - 'log_name': 'talos', 04:15:37 INFO - 'log_to_console': True, 04:15:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:15:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:15:37 INFO - 'opt_config_files': (), 04:15:37 INFO - 'pip_index': False, 04:15:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:37 INFO - 'cmd': ('bash', 04:15:37 INFO - '-c', 04:15:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:37 INFO - 'enabled': True, 04:15:37 INFO - 'halt_on_failure': False, 04:15:37 INFO - 'name': 'check_screen_resolution'},), 04:15:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:37 INFO - 'cmd': ('bash', 04:15:37 INFO - '-c', 04:15:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:37 INFO - 'enabled': True, 04:15:37 INFO - 'halt_on_failure': False, 04:15:37 INFO - 'name': 'check_screen_resolution'},), 04:15:37 INFO - 'run_cmd_checks_enabled': True, 04:15:37 INFO - 'sps_profile': False, 04:15:37 INFO - 'sps_profile_interval': 0, 04:15:37 INFO - 'suite': 'dromaeojs', 04:15:37 INFO - 'system_bits': '32', 04:15:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:15:37 INFO - 'title': 't-yosemite-r7-0348', 04:15:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:15:37 INFO - 'use_talos_json': True, 04:15:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:15:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:15:37 INFO - 'work_dir': 'build'} 04:15:37 INFO - [mozharness: 2017-01-31 12:15:37.033921Z] Running clobber step. 04:15:37 INFO - Running pre-action listener: _resource_record_pre_action 04:15:37 INFO - Running main action method: clobber 04:15:37 INFO - rmtree: /builds/slave/test/build 04:15:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:15:39 INFO - Running post-action listener: _resource_record_post_action 04:15:39 INFO - [mozharness: 2017-01-31 12:15:39.501400Z] Finished clobber step (success) 04:15:39 INFO - [mozharness: 2017-01-31 12:15:39.501540Z] Running read-buildbot-config step. 04:15:39 INFO - Running pre-action listener: _resource_record_pre_action 04:15:39 INFO - Running main action method: read_buildbot_config 04:15:39 INFO - Using buildbot properties: 04:15:39 INFO - { 04:15:39 INFO - "project": "", 04:15:39 INFO - "product": "firefox", 04:15:39 INFO - "script_repo_revision": "production", 04:15:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:15:39 INFO - "repository": "", 04:15:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:15:39 INFO - "buildid": "20170131030205", 04:15:39 INFO - "stage_platform": "macosx64", 04:15:39 INFO - "basedir": "/builds/slave/test", 04:15:39 INFO - "nightly_build": "True", 04:15:39 INFO - "buildnumber": 45, 04:15:39 INFO - "slavename": "t-yosemite-r7-0348", 04:15:39 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:15:39 INFO - "platform": "yosemite_r7", 04:15:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:15:39 INFO - "branch": "mozilla-central", 04:15:39 INFO - "repo_path": "mozilla-central", 04:15:39 INFO - "revision": "9c06e744b1befb3a2e2fdac7414ce18220774a1d", 04:15:39 INFO - "pgo_build": "False", 04:15:39 INFO - "builduid": "98f0121b729d47b1bfa7e25b44eb052a", 04:15:39 INFO - "slavebuilddir": "test" 04:15:39 INFO - } 04:15:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:15:39 INFO - Running post-action listener: _resource_record_post_action 04:15:39 INFO - [mozharness: 2017-01-31 12:15:39.507487Z] Finished read-buildbot-config step (success) 04:15:39 INFO - [mozharness: 2017-01-31 12:15:39.507709Z] Running download-and-extract step. 04:15:39 INFO - Running pre-action listener: _resource_record_pre_action 04:15:39 INFO - Running main action method: download_and_extract 04:15:39 INFO - mkdir: /builds/slave/test/build/tests 04:15:39 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')]} 04:15:39 INFO - trying https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:15:39 INFO - Downloading https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/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 04:15:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/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 04:15:40 INFO - Downloaded 1199 bytes. 04:15:40 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:15:40 INFO - Using the following test package requirements: 04:15:40 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:15:40 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:15:40 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:15:40 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'jsshell-mac.zip'], 04:15:40 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:15:40 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:15:40 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:15:40 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:15:40 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:15:40 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:15:40 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:15:40 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:15:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:15:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:15:40 INFO - Fetch https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:15:41 INFO - Expected file size: 16368105 04:15:41 INFO - Obtained file size: 16368105 04:15:48 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 04:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:15:49 INFO - Expected file size: 16368105 04:15:49 INFO - Obtained file size: 16368105 04:15:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:15:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:15:55 INFO - Fetch https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:15:56 INFO - Expected file size: 14403454 04:15:56 INFO - Obtained file size: 14403454 04:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:57 INFO - trying https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:15:57 INFO - Downloading https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:15:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/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 04:15:59 INFO - Downloaded 70260609 bytes. 04:15:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:15:59 INFO - mkdir: /builds/slave/test/properties 04:15:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:15:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:15:59 INFO - Contents: 04:15:59 INFO - build_url:https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:16:00 INFO - Running post-action listener: _resource_record_post_action 04:16:00 INFO - Running post-action listener: set_extra_try_arguments 04:16:00 INFO - [mozharness: 2017-01-31 12:16:00.294569Z] Finished download-and-extract step (success) 04:16:00 INFO - [mozharness: 2017-01-31 12:16:00.294699Z] Running populate-webroot step. 04:16:00 INFO - Running pre-action listener: _resource_record_pre_action 04:16:00 INFO - Running main action method: populate_webroot 04:16:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:16:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:16:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:16:00 INFO - '--tppagecycles', 04:16:00 INFO - '7'], 04:16:00 INFO - 'tests': ['tsvgm']}}, 04:16:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:16:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:16:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:16:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:16:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:16:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['damp', 'tps']}, 04:16:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'tests': ['damp', 'tps']}, 04:16:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['dromaeo_dom']}, 04:16:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:16:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['basic_compositor_video']}, 04:16:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:16:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['a11yr', 04:16:00 INFO - 'ts_paint', 04:16:00 INFO - 'tpaint', 04:16:00 INFO - 'sessionrestore', 04:16:00 INFO - 'sessionrestore_no_auto_restore', 04:16:00 INFO - 'tabpaint']}, 04:16:00 INFO - 'other-e10s': {'tests': ['a11yr', 04:16:00 INFO - 'ts_paint', 04:16:00 INFO - 'tpaint', 04:16:00 INFO - 'sessionrestore', 04:16:00 INFO - 'sessionrestore_no_auto_restore', 04:16:00 INFO - 'tabpaint']}, 04:16:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:16:00 INFO - 'ts_paint', 04:16:00 INFO - 'tpaint', 04:16:00 INFO - 'sessionrestore', 04:16:00 INFO - 'sessionrestore_no_auto_restore', 04:16:00 INFO - 'tabpaint']}, 04:16:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:16:00 INFO - 'ts_paint', 04:16:00 INFO - 'tpaint', 04:16:00 INFO - 'sessionrestore', 04:16:00 INFO - 'sessionrestore_no_auto_restore', 04:16:00 INFO - 'tabpaint']}, 04:16:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['a11yr', 04:16:00 INFO - 'ts_paint', 04:16:00 INFO - 'tpaint', 04:16:00 INFO - 'sessionrestore', 04:16:00 INFO - 'sessionrestore_no_auto_restore', 04:16:00 INFO - 'tabpaint']}, 04:16:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['a11yr', 04:16:00 INFO - 'ts_paint', 04:16:00 INFO - 'tpaint', 04:16:00 INFO - 'sessionrestore', 04:16:00 INFO - 'sessionrestore_no_auto_restore', 04:16:00 INFO - 'tabpaint']}, 04:16:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['tsvgx', 04:16:00 INFO - 'tsvgr_opacity', 04:16:00 INFO - 'tart', 04:16:00 INFO - 'tscrollx', 04:16:00 INFO - 'cart', 04:16:00 INFO - 'tsvg_static']}, 04:16:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:16:00 INFO - 'tsvgr_opacity', 04:16:00 INFO - 'tart', 04:16:00 INFO - 'tscrollx', 04:16:00 INFO - 'cart', 04:16:00 INFO - 'tsvg_static']}, 04:16:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'talos_options': ['--disable-e10s'], 04:16:00 INFO - 'tests': ['tp5o']}, 04:16:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:16:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'talos_options': ['--disable-e10s', 04:16:00 INFO - '--xperf_path', 04:16:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:00 INFO - 'tests': ['tp5n']}, 04:16:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:16:00 INFO - 'talos_options': ['--xperf_path', 04:16:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:16:00 INFO - 'tests': ['tp5n']}}, 04:16:00 INFO - 'talos.zip': {'path': '', 04:16:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:16:00 INFO - Running post-action listener: _resource_record_post_action 04:16:00 INFO - [mozharness: 2017-01-31 12:16:00.313512Z] Finished populate-webroot step (success) 04:16:00 INFO - [mozharness: 2017-01-31 12:16:00.313613Z] Running create-virtualenv step. 04:16:00 INFO - Running pre-action listener: _resource_record_pre_action 04:16:00 INFO - Running main action method: create_virtualenv 04:16:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:16:00 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 04:16:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:16:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:16:00 INFO - Using real prefix '/tools/python27' 04:16:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:16:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:16:03 INFO - Installing pip.................done. 04:16:03 INFO - Return code: 0 04:16:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:16:04 INFO - Reading from file tmpfile_stdout 04:16:04 INFO - Output received: 04:16:04 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) 04:16:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:16:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:04 INFO - 'HOME': '/Users/cltbld', 04:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:04 INFO - 'LOGNAME': 'cltbld', 04:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:04 INFO - 'MOZ_NO_REMOTE': '1', 04:16:04 INFO - 'NO_EM_RESTART': '1', 04:16:04 INFO - 'PAGER': '/bin/cat', 04:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:04 INFO - 'PWD': '/builds/slave/test', 04:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:04 INFO - 'SHELL': '/bin/bash', 04:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:04 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:04 INFO - 'USER': 'cltbld', 04:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:04 INFO - 'XPC_FLAGS': '0x0', 04:16:04 INFO - 'XPC_SERVICE_NAME': '0', 04:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:04 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 04:16:04 INFO - Cleaning up... 04:16:04 INFO - Return code: 0 04:16:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:16:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:04 INFO - 'HOME': '/Users/cltbld', 04:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:04 INFO - 'LOGNAME': 'cltbld', 04:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:04 INFO - 'MOZ_NO_REMOTE': '1', 04:16:04 INFO - 'NO_EM_RESTART': '1', 04:16:04 INFO - 'PAGER': '/bin/cat', 04:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:04 INFO - 'PWD': '/builds/slave/test', 04:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:04 INFO - 'SHELL': '/bin/bash', 04:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:04 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:04 INFO - 'USER': 'cltbld', 04:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:04 INFO - 'XPC_FLAGS': '0x0', 04:16:04 INFO - 'XPC_SERVICE_NAME': '0', 04:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:04 INFO - Downloading/unpacking psutil>=3.1.1 04:16:04 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 04:16:04 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 04:16:04 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 04:16:04 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 04:16:04 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 04:16:04 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 04:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:16:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:08 INFO - Installing collected packages: psutil 04:16:08 INFO - Running setup.py install for psutil 04:16:08 INFO - building 'psutil._psutil_osx' extension 04:16:08 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 04:16:09 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 04:16:09 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 04:16:09 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 04:16:09 INFO - building 'psutil._psutil_posix' extension 04:16:09 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 04:16:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:16:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:16:09 INFO - ^ 04:16:09 INFO - 1 warning generated. 04:16:09 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 04:16:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:16:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:16:09 INFO - Successfully installed psutil 04:16:09 INFO - Cleaning up... 04:16:09 INFO - Return code: 0 04:16:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:16:09 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')]} 04:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:09 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')]} 04:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:09 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:09 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 04:16:09 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 04:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:09 INFO - 'HOME': '/Users/cltbld', 04:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:09 INFO - 'LOGNAME': 'cltbld', 04:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:09 INFO - 'MOZ_NO_REMOTE': '1', 04:16:09 INFO - 'NO_EM_RESTART': '1', 04:16:09 INFO - 'PAGER': '/bin/cat', 04:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:09 INFO - 'PWD': '/builds/slave/test', 04:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:09 INFO - 'SHELL': '/bin/bash', 04:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:09 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:09 INFO - 'USER': 'cltbld', 04:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:09 INFO - 'XPC_FLAGS': '0x0', 04:16:09 INFO - 'XPC_SERVICE_NAME': '0', 04:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:16:12 INFO - Installing collected packages: mozsystemmonitor 04:16:12 INFO - Running setup.py install for mozsystemmonitor 04:16:12 INFO - Successfully installed mozsystemmonitor 04:16:12 INFO - Cleaning up... 04:16:12 INFO - Return code: 0 04:16:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:12 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:12 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 04:16:12 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 04:16:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:12 INFO - 'HOME': '/Users/cltbld', 04:16:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:12 INFO - 'LOGNAME': 'cltbld', 04:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:12 INFO - 'MOZ_NO_REMOTE': '1', 04:16:12 INFO - 'NO_EM_RESTART': '1', 04:16:12 INFO - 'PAGER': '/bin/cat', 04:16:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:12 INFO - 'PWD': '/builds/slave/test', 04:16:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:12 INFO - 'SHELL': '/bin/bash', 04:16:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:12 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:12 INFO - 'USER': 'cltbld', 04:16:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:12 INFO - 'XPC_FLAGS': '0x0', 04:16:12 INFO - 'XPC_SERVICE_NAME': '0', 04:16:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:13 INFO - Downloading/unpacking jsonschema==2.5.1 04:16:13 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 04:16:13 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 04:16:13 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 04:16:13 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 04:16:13 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 04:16:13 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 04:16:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:16:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:16:15 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 04:16:15 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 04:16:15 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 04:16:15 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 04:16:15 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 04:16:15 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 04:16:16 INFO - Downloading functools32-3.2.3-2.tar.gz 04:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:16:16 INFO - warning: no files found matching '*.txt' 04:16:16 INFO - no previously-included directories found matching 'build' 04:16:16 INFO - no previously-included directories found matching 'dist' 04:16:16 INFO - no previously-included directories found matching '.git*' 04:16:16 INFO - Installing collected packages: jsonschema, functools32 04:16:16 INFO - Running setup.py install for functools32 04:16:16 INFO - warning: no files found matching '*.txt' 04:16:16 INFO - no previously-included directories found matching 'build' 04:16:16 INFO - no previously-included directories found matching 'dist' 04:16:16 INFO - no previously-included directories found matching '.git*' 04:16:16 INFO - Successfully installed jsonschema functools32 04:16:16 INFO - Cleaning up... 04:16:16 INFO - Return code: 0 04:16:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:16:16 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')]} 04:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:16 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')]} 04:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:16 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:16 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 04:16:16 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 04:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:16 INFO - 'HOME': '/Users/cltbld', 04:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:16 INFO - 'LOGNAME': 'cltbld', 04:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:16 INFO - 'MOZ_NO_REMOTE': '1', 04:16:16 INFO - 'NO_EM_RESTART': '1', 04:16:16 INFO - 'PAGER': '/bin/cat', 04:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:16 INFO - 'PWD': '/builds/slave/test', 04:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:16 INFO - 'SHELL': '/bin/bash', 04:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:16 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:16 INFO - 'USER': 'cltbld', 04:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:16 INFO - 'XPC_FLAGS': '0x0', 04:16:16 INFO - 'XPC_SERVICE_NAME': '0', 04:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:16:16 INFO - Cleaning up... 04:16:16 INFO - Return code: 0 04:16:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:16:16 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')]} 04:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:16 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')]} 04:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:16 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:16 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 04:16:16 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 04:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:16 INFO - 'HOME': '/Users/cltbld', 04:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:16 INFO - 'LOGNAME': 'cltbld', 04:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:16 INFO - 'MOZ_NO_REMOTE': '1', 04:16:16 INFO - 'NO_EM_RESTART': '1', 04:16:16 INFO - 'PAGER': '/bin/cat', 04:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:16 INFO - 'PWD': '/builds/slave/test', 04:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:16 INFO - 'SHELL': '/bin/bash', 04:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:16 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:16 INFO - 'USER': 'cltbld', 04:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:16 INFO - 'XPC_FLAGS': '0x0', 04:16:16 INFO - 'XPC_SERVICE_NAME': '0', 04:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:17 INFO - Downloading/unpacking blobuploader==1.2.4 04:16:17 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 04:16:17 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 04:16:17 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 04:16:17 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 04:16:17 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 04:16:17 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 04:16:19 INFO - Downloading blobuploader-1.2.4.tar.gz 04:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:16:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:16:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:16:21 INFO - Downloading docopt-0.6.1.tar.gz 04:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:16:21 INFO - Installing collected packages: blobuploader, requests, docopt 04:16:21 INFO - Running setup.py install for blobuploader 04:16:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:16:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:16:21 INFO - Running setup.py install for requests 04:16:21 INFO - Running setup.py install for docopt 04:16:22 INFO - Successfully installed blobuploader requests docopt 04:16:22 INFO - Cleaning up... 04:16:22 INFO - Return code: 0 04:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:22 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:22 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 04:16:22 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 04:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:22 INFO - 'HOME': '/Users/cltbld', 04:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:22 INFO - 'LOGNAME': 'cltbld', 04:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:22 INFO - 'MOZ_NO_REMOTE': '1', 04:16:22 INFO - 'NO_EM_RESTART': '1', 04:16:22 INFO - 'PAGER': '/bin/cat', 04:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:22 INFO - 'PWD': '/builds/slave/test', 04:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:22 INFO - 'SHELL': '/bin/bash', 04:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:22 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:22 INFO - 'USER': 'cltbld', 04:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:22 INFO - 'XPC_FLAGS': '0x0', 04:16:22 INFO - 'XPC_SERVICE_NAME': '0', 04:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:22 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-MSCR0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:22 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-tix9kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:22 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-WCuNBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:22 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-6Jsynq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:22 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-IzEs6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:22 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-svhI4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-uYMTf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-JIQbB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-uGN5ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-uRjiRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-RT556S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-B0YV70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-va_wu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-D2CyLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-VrDA_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:23 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-CSpkKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:24 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-sSt5gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:16:24 INFO - Running setup.py install for manifestparser 04:16:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for mozcrash 04:16:24 INFO - Running setup.py install for mozdebug 04:16:24 INFO - Running setup.py install for mozdevice 04:16:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:16:24 INFO - Running setup.py install for mozfile 04:16:24 INFO - Running setup.py install for mozhttpd 04:16:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozinfo 04:16:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozInstall 04:16:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozleak 04:16:25 INFO - Running setup.py install for mozlog 04:16:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for moznetwork 04:16:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:16:25 INFO - Running setup.py install for mozprocess 04:16:26 INFO - Running setup.py install for mozprofile 04:16:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:16:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:16:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:16:26 INFO - Running setup.py install for mozrunner 04:16:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:16:26 INFO - Running setup.py install for mozscreenshot 04:16:26 INFO - Running setup.py install for moztest 04:16:26 INFO - Running setup.py install for mozversion 04:16:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:16:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:16:26 INFO - Cleaning up... 04:16:26 INFO - Return code: 0 04:16:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:26 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:26 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 04:16:26 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 04:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:26 INFO - 'HOME': '/Users/cltbld', 04:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:26 INFO - 'LOGNAME': 'cltbld', 04:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:26 INFO - 'MOZ_NO_REMOTE': '1', 04:16:26 INFO - 'NO_EM_RESTART': '1', 04:16:26 INFO - 'PAGER': '/bin/cat', 04:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:26 INFO - 'PWD': '/builds/slave/test', 04:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:26 INFO - 'SHELL': '/bin/bash', 04:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:26 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:26 INFO - 'USER': 'cltbld', 04:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:26 INFO - 'XPC_FLAGS': '0x0', 04:16:26 INFO - 'XPC_SERVICE_NAME': '0', 04:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-tO6IQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-lfEluN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-OJ_keL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-CbSerF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-XSufsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-GR34i_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-PMIs7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-9XTRYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:27 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)) 04:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:27 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-ZhYcSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-rbP3AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-KeW3X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-sxKINj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-OlKeo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-Mnud0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-HwHNQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-A3HyrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:28 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)) 04:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:28 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-2PWbSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:28 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)) 04:16:28 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)) 04:16:28 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)) 04:16:28 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)) 04:16:28 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)) 04:16:28 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)) 04:16:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:28 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 04:16:28 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 04:16:28 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 04:16:28 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 04:16:28 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 04:16:28 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 04:16:31 INFO - Downloading blessings-1.6.tar.gz 04:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:16:31 INFO - Installing collected packages: blessings 04:16:31 INFO - Running setup.py install for blessings 04:16:31 INFO - Successfully installed blessings 04:16:31 INFO - Cleaning up... 04:16:31 INFO - Return code: 0 04:16:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:16:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:32 INFO - Reading from file tmpfile_stdout 04:16:32 INFO - Current package versions: 04:16:32 INFO - blessings == 1.6 04:16:32 INFO - blobuploader == 1.2.4 04:16:32 INFO - docopt == 0.6.1 04:16:32 INFO - functools32 == 3.2.3-2 04:16:32 INFO - jsonschema == 2.5.1 04:16:32 INFO - manifestparser == 1.1 04:16:32 INFO - mozInstall == 1.12 04:16:32 INFO - mozcrash == 1.0 04:16:32 INFO - mozdebug == 0.1 04:16:32 INFO - mozdevice == 0.49 04:16:32 INFO - mozfile == 1.2 04:16:32 INFO - mozhttpd == 0.7 04:16:32 INFO - mozinfo == 0.9 04:16:32 INFO - mozleak == 0.1 04:16:32 INFO - mozlog == 3.4 04:16:32 INFO - moznetwork == 0.27 04:16:32 INFO - mozprocess == 0.24 04:16:32 INFO - mozprofile == 0.28 04:16:32 INFO - mozrunner == 6.13 04:16:32 INFO - mozscreenshot == 0.1 04:16:32 INFO - mozsystemmonitor == 0.3 04:16:32 INFO - moztest == 0.8 04:16:32 INFO - mozversion == 1.4 04:16:32 INFO - psutil == 3.1.1 04:16:32 INFO - requests == 1.2.3 04:16:32 INFO - wsgiref == 0.1.2 04:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:32 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:32 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 04:16:32 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 04:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:32 INFO - 'HOME': '/Users/cltbld', 04:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:32 INFO - 'LOGNAME': 'cltbld', 04:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:32 INFO - 'MOZ_NO_REMOTE': '1', 04:16:32 INFO - 'NO_EM_RESTART': '1', 04:16:32 INFO - 'PAGER': '/bin/cat', 04:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:32 INFO - 'PWD': '/builds/slave/test', 04:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:32 INFO - 'SHELL': '/bin/bash', 04:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:32 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:32 INFO - 'USER': 'cltbld', 04:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:32 INFO - 'XPC_FLAGS': '0x0', 04:16:32 INFO - 'XPC_SERVICE_NAME': '0', 04:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 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)) 04:16:32 INFO - Cleaning up... 04:16:32 INFO - Return code: 0 04:16:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:32 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 0x103015b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103018768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3dcf290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, '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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:16:32 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 04:16:32 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 04:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:32 INFO - 'HOME': '/Users/cltbld', 04:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:32 INFO - 'LOGNAME': 'cltbld', 04:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:32 INFO - 'MOZ_NO_REMOTE': '1', 04:16:32 INFO - 'NO_EM_RESTART': '1', 04:16:32 INFO - 'PAGER': '/bin/cat', 04:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:32 INFO - 'PWD': '/builds/slave/test', 04:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:32 INFO - 'SHELL': '/bin/bash', 04:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:32 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:32 INFO - 'USER': 'cltbld', 04:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:32 INFO - 'XPC_FLAGS': '0x0', 04:16:32 INFO - 'XPC_SERVICE_NAME': '0', 04:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:16:32 INFO - Cleaning up... 04:16:32 INFO - Return code: 0 04:16:32 INFO - Running post-action listener: _resource_record_post_action 04:16:32 INFO - Running post-action listener: _start_resource_monitoring 04:16:32 INFO - Starting resource monitoring. 04:16:32 INFO - [mozharness: 2017-01-31 12:16:32.756366Z] Finished create-virtualenv step (success) 04:16:32 INFO - [mozharness: 2017-01-31 12:16:32.757293Z] Running install step. 04:16:32 INFO - Running pre-action listener: _resource_record_pre_action 04:16:32 INFO - Running main action method: install 04:16:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:33 INFO - Reading from file tmpfile_stdout 04:16:33 INFO - Detecting whether we're running mozinstall >=1.0... 04:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:16:33 INFO - Reading from file tmpfile_stdout 04:16:33 INFO - Output received: 04:16:33 INFO - Usage: mozinstall [options] installer 04:16:33 INFO - Options: 04:16:33 INFO - -h, --help show this help message and exit 04:16:33 INFO - -d DEST, --destination=DEST 04:16:33 INFO - Directory to install application into. [default: 04:16:33 INFO - "/builds/slave/test"] 04:16:33 INFO - --app=APP Application being installed. [default: firefox] 04:16:33 INFO - mkdir: /builds/slave/test/build/application 04:16:33 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'] 04:16:33 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 04:16:55 INFO - Reading from file tmpfile_stdout 04:16:55 INFO - Output received: 04:16:55 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:16:55 INFO - Running post-action listener: _resource_record_post_action 04:16:55 INFO - [mozharness: 2017-01-31 12:16:55.307196Z] Finished install step (success) 04:16:55 INFO - [mozharness: 2017-01-31 12:16:55.307359Z] Running run-tests step. 04:16:55 INFO - Running pre-action listener: _resource_record_pre_action 04:16:55 INFO - Running main action method: run_tests 04:16:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:16:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:16:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:16:55 INFO - 2017-01-31 04:16:55.321 screenresolution[1631:7481] starting screenresolution argv=screenresolution get 04:16:55 INFO - 2017-01-31 04:16:55.324 screenresolution[1631:7481] Display 0: 1600x1200x32@60 04:16:55 INFO - 2017-01-31 04:16:55.330 screenresolution[1632:7483] starting screenresolution argv=screenresolution list 04:16:55 INFO - Available Modes on Display 0 04:16:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:16:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:16:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:16:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:16:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:16:55 INFO - Intel Iris: 04:16:55 INFO - Chipset Model: Intel Iris 04:16:55 INFO - Type: GPU 04:16:55 INFO - Bus: Built-In 04:16:55 INFO - VRAM (Dynamic, Max): 1536 MB 04:16:55 INFO - Vendor: Intel (0x8086) 04:16:55 INFO - Device ID: 0x0a2e 04:16:55 INFO - Revision ID: 0x0009 04:16:55 INFO - Displays: 04:16:55 INFO - Display: 04:16:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:16:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:16:55 INFO - Display Serial Number: 1600x1200 60 04:16:55 INFO - Main Display: Yes 04:16:55 INFO - Mirror: Off 04:16:55 INFO - Online: Yes 04:16:55 INFO - Rotation: Supported 04:16:55 INFO - Return code: 0 04:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:16:55 INFO - Python 2.7.3 04:16:55 INFO - Return code: 0 04:16:55 INFO - grabbing minidump binary from tooltool 04:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:16:55 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 0x102df3d30>, '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 0x102d1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102de8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:16:55 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 04:16:55 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 04:16:55 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 04:16:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:16:55 INFO - Return code: 0 04:16:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:16:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:16:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:16:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:16:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:16:55 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0348', '--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 04:16:55 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0348 --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 04:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kKSxfjkcjg/Render', 04:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:55 INFO - 'HOME': '/Users/cltbld', 04:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:55 INFO - 'LOGNAME': 'cltbld', 04:16:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:16:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:55 INFO - 'MOZ_NO_REMOTE': '1', 04:16:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:16:55 INFO - 'NO_EM_RESTART': '1', 04:16:55 INFO - 'PAGER': '/bin/cat', 04:16:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:55 INFO - 'PWD': '/builds/slave/test', 04:16:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:55 INFO - 'SHELL': '/bin/bash', 04:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QlVBBgHnAU/Listeners', 04:16:55 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:55 INFO - 'USER': 'cltbld', 04:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:55 INFO - 'XPC_FLAGS': '0x0', 04:16:55 INFO - 'XPC_SERVICE_NAME': '0', 04:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:55 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0348', '--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 04:16:55 INFO - mozversion application_buildid: 20170131030205 04:16:55 INFO - mozversion application_changeset: 9c06e744b1befb3a2e2fdac7414ce18220774a1d 04:16:55 INFO - mozversion application_display_name: Nightly 04:16:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:16:55 INFO - mozversion application_name: Firefox 04:16:55 INFO - mozversion application_remotingname: firefox 04:16:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:16:55 INFO - mozversion application_vendor: Mozilla 04:16:55 INFO - mozversion application_version: 54.0a1 04:16:55 INFO - mozversion platform_buildid: 20170131030205 04:16:55 INFO - mozversion platform_changeset: 9c06e744b1befb3a2e2fdac7414ce18220774a1d 04:16:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:16:55 INFO - mozversion platform_version: 54.0a1 04:16:55 INFO - using testdate: 1485865015 04:16:55 INFO - actual date: 1485865015 04:16:55 INFO - starting webserver on 'localhost:49243' 04:16:55 INFO - SUITE-START | Running 2 tests 04:16:55 INFO - TEST-START | dromaeo_css 04:16:55 INFO - operating with platform_type : mac_ 04:16:55 INFO - Initialising browser for dromaeo_css test... 04:16:55 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpSLsNPH/profile http://localhost:49243/getInfo.html) 04:16:58 INFO - PROCESS | 1642 | 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] 04:16:58 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 04:16:58 INFO - PROCESS | 1642 | colorDepth:24 04:16:58 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 04:16:58 INFO - PROCESS | 1642 | __metrics 04:16:58 INFO - PROCESS | 1642 | 1485865018638 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/FirefoxNightly.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/FirefoxNightly.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 04:16:58 INFO - PROCESS | 1642 | 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" 04:16:58 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:59 INFO - TEST-INFO | 1642: exit 0 04:16:59 INFO - Browser initialized. 04:16:59 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:59 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpSLsNPH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:11 INFO - PROCESS | 1645 | RSS: Main: 237096960 04:17:11 INFO - PROCESS | 1645 | 04:20:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 04:20:17 INFO - PROCESS | 1645 | RSS: Main: 352436224 04:20:17 INFO - PROCESS | 1645 | 04:22:00 INFO - PROCESS | 1645 | ************************* 04:22:00 INFO - PROCESS | 1645 | A coding exception was thrown and uncaught in a Task. 04:22:00 INFO - PROCESS | 1645 | 04:22:00 ERROR - PROCESS | 1645 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:22:00 INFO - PROCESS | 1645 | Full stack: 04:22:00 INFO - PROCESS | 1645 | ************************* 04:23:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 04:23:21 INFO - PROCESS | 1645 | RSS: Main: 371548160 04:23:21 INFO - PROCESS | 1645 | 04:26:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 04:26:24 INFO - PROCESS | 1645 | RSS: Main: 333369344 04:26:24 INFO - PROCESS | 1645 | 04:29:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 04:29:27 INFO - PROCESS | 1645 | RSS: Main: 347623424 04:29:27 INFO - PROCESS | 1645 | 04:32:31 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 04:32:31 INFO - PROCESS | 1645 | RSS: Main: 329965568 04:32:31 INFO - PROCESS | 1645 | 04:35:34 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 04:35:35 INFO - PROCESS | 1645 | RSS: Main: 415039488 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | __start_tp_report 04:35:35 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 04:35:35 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 04:35:35 INFO - PROCESS | 1645 | |i|pagename|runs| 04:35:35 INFO - PROCESS | 1645 | |0;dojo.html;3293.851776670051;3298.5052242163674;3315.4032274190645;3335.1495368680985;3357.328534293142;5514.6691198528115;5598.860028499284;5631.57763167762;5640.1821735848325;5668.319801623811;5628.802991895274;5653.038983372826;5655.943440565593;5658.462446067627;5665.12190610455;5127.974360128194;5162.5095615916425;5171.198464238049;5206.45332240115;5232.3459567554055;5268.92096618551;5273.8945221095555;5276.234945932837;5295.391030031538;5301.6818990860675;5264.657797243186;5269.65747226431;5269.683818970855;5274.4198138204765;5276.736163191844;4360.171567402215;5179.741012949337;5246.973765131169;5265.157574788034;5277.762500687479;5009.974950125263;5140.126178549618;5178.533931946105;5234.18870075139;5256.159014557691;5687.544996400278;5736.8852622947725;5758.539316854642;5776.422357764232;5780.53755699543;6016.4885984691355;6032.245969253843;6218.22272215973;6235.501159907242;6243.7814676486105;5153.819616313411;5285.973570132163;6137.836677219332;6149.907751383733;6202.193714817045;4844.927326090112;5864.794732184353;5890.705464726832;5899.085641725539;5928.02187639039;6256.968715156395;6264.342244064399;6272.905906411407;6276.843078923063;6307.96846015767;5249.792547713971;5965.55258355625;5998.730057147439;6058.0610005449225;6134.6319220846;6143.696113957266;6175.2898416681755;6178.011518157074;6178.135061091451;6178.505719542426;6023.548233882477;6143.354947730513;6144.324124346318;6150.292716337589;6167.982282923357;6085.878282434326;6108.463491842691;6137.140800287963;6143.416375444325;6163.476104531075;4988.176950803077;5025.3718285214345;5029.421616514148;5039.496050395004;5081.59347252219;4449.466064072268;4456.9331460028125;4457.108578284357;4470.971676514355;4491.775411229425;5561.138023606267;5794;5799.217105690762;5816.2148110005455;5822.388649191858;6041.124037014696;6060.515158787286;6087.1173679816175;6109.16175461965;6132.754689812357;5473.17902314648;5493.587980901449;5519.39286678465;5534.335879694462;5569.972150139304;5268.157094864803;5390.946090539122;5393.083175860034;5406.486383793534;5435.157550579587;6208.906866397098;6272.09054687077;6281.968590157111;6284.402981716729;6297.559170857996;6108.938910610837;6117.9187529241;6119.654885717031;6135.785247516315;6189.35011823752;6151.2618485781995;6275.215598050244;6289.49684025286;6298.0552917062805;6300.684965751602;5997.010493268562;6166.321704612399;6174.413430724074;6178.011518157253;6192.907106393318;6115.696722738907;6139.154842350657;6227.314995350598;6229.50163986889;6250.374962503895;6076.72654730547;6092.177556029879;6112.17485639433;6112.938870611415;6140.785072522441;6051.455369016633;6086.908696369646;6098.542609304409;6101.176341193882;6115.571909966259;5663.122216056518;5740.971295143665;5852.707364631837;5922.052471604523;5941.287045554561;6086.2087928569;6088.939110608837;6124.075808605272;6129.754809807558;6132.998044146438;5951.940480595139;5958.791442299672;5960.403959604005;5965.34381218057;6013.909791353046 04:35:35 INFO - PROCESS | 1645 | |1;ext.html;440.5770460358056;497.48261807719973;501.56614528432914;503.1873524258323;503.3758139906516;40750.38874416883;42589.57410425899;42717.78641106798;42812.78593607035;44479.55520444795;42243.78878105613;42573.93565160873;42635.573644263546;42715;42737.35893961593;8510.957445212765;9363.063693630616;9565.569549370295;9579.712608621718;9585.2811039172;8461.15388461155;11319.490622921947;11624.825627615592;11647.534098636044;11866.288022718622;11601.013913817336;11601.825972610417;11654.766904661901;11679.941600291986;11694.415279236047;8553.272971797405;11687.5909343173;11757.764844703144;11770.293782373043;11813;8539.701110461167;11201;11488.885111148866;11492.540298388054;11776.882231177664;41916.580834191875;42127;42276.57723422788;42326;42385.57614423847;4025.0339918419663;4029.3351596986463;4035.2938235808615;4079.3269110596716;4167.354060120686;31422.214444638837;31423.05730828079;31423.84288078568;31458.528122077958;31502.842485787653;2908.2729317670583;3044.5737596736476;3114.6262448506322;3146.197719581515;3146.2449012237125;464823;466187;469069;472250.6387468041;476986;52576.47423525792;59271.81456370935;59595.80808383895;59607;59669;63758.406039849375;63805.68097159577;63812.72374552483;64055;64166;16075.517734467985;16332.67334653324;16348.182590870409;16358.672826543641;16396.75404868928;11261.155413344031;11604.825927611093;12633.873661263453;12673.302968336646;12711.491540338282;13864.653383665489;15506.767398489032;15876.856624355794;15937.123458209679;16263.593410164603;6148.723307451175;6407.903881441782;6446.64851225421;6782.457403407814;6862.35799217305;858.5449711652834;865.7359505350803;872.6553011560399;876.369014309696;903.3902116071625;29679.554806677486;29985.550216746768;30020.399592008038;30556.388872222433;30697.84651076775;976.72651657536;1041.7083216699336;1073.3881687438086;1079.6868908016745;1081.9729506762394;15136.805163251267;15478.613034673997;15533.844661553467;15711.171989041854;15999.360025599079;610348;617400;617617;626332;626550;57386.71306643524;57476.42523574794;57544.8491030177;57619.711901441064;57634;1407.029149886575;1411.745885740556;1414.285785678226;1417.574727581742;1438.0077746354982;28614.42771144607;28880;29286.678330417395;29389.824407024335;30202.395952081275;49334.75332623314;49726;49772.00455990932;49780;49853.750731247565;511499;512591;512882;513531;515296;38689.80655096706;38822.61177388303;39101.41347879727;39275.41086883756;39900.80049599733;44913.550864490935;45531.86170345768;45617.31574026459;46471.767641161576;46474.76762616165;33067;37848.81075594604;38143.237135257696;38272.61727382691;39451.40822887602;36309.553566965165;36451.45322820107;37873.81063094667;38729.41905871358;39119.80440097781;37413.438798417505;37573.4363984535;37848;38015.80992095022;38181;31991.68008319887;33510.32979340448;33628.80280148853;34143.65856341405;34686.47970280498;22559.09763609437;24543.50912981766;24958.5150885836;25186.874065629556;25224.873875630503 04:35:35 INFO - PROCESS | 1645 | |2;jquery.html;345.4990264117029;376.1255081934503;394.2902775004992;397.6361629109365;428.88634511854366;15122;16480.752788708178;20014.29949951752;20683;21638.675419868716;20882.686759698616;22012.79872012798;22032.228871989482;22298.331050068482;22445.887770561123;5762.9135562966485;6123.632582045091;6129.019356902896;6132.2028136342215;6141.078838174287;4593.173228818811;6049.606775559597;6076.118962750398;6090.451859332659;6093.268807743077;5759.568032397566;6045.818625441244;6056.303525094627;6064.211652485171;6065.787697430591;5870.970645146747;5903.35063143054;5912.290525136968;5917.319508256563;6018.097285407181;4438.289873620238;5057.190849464068;5135.717535535544;5168.302279192299;5216.139337009389;26148.477030459475;26256.555889426076;26273;26283.73716262832;26558;2449.6448015037718;2605.1142611511987;2664.760171584547;2706.1340371081064;2727.372704278027;10172.94913525435;10193.133583645398;10207.62197103389;10558.366498010144;10643.361398316125;1755.6049888774924;1810.4840120565575;1810.6107186954737;1813.6010077782867;1815.9182836772245;145644.68639242506;147366;148325;148529;151544;10898.237123401444;11649.359285239223;11866.584669536525;11868.465919033664;12066.113224144236;14516.129032258099;15001.624959375884;35667;36920.26159476795;39535;7851;10925.672229833117;11394.25937314072;11501.42492875353;11508.98592746266;6909.412699920563;8290.170982901662;8858;8951.552422378854;9079.546022698838;8652.923298408463;10342.172626189888;10797.62208322705;10869.021788039114;10872;3374.5320785458402;3450.551428314303;3959.4852669152824;4028.5971402860064;4092.9795351023054;1012.3268026762267;1012.71643939697;1018.0938964321759;1024.416082832793;1051.038299955532;21307.57384852294;21665.78334216669;21669;21964.231251906425;22105.778942210694;937.1378331934636;1016.1718199667248;1028.9022542858415;1045.6078970386104;1049.7900419916048;10148;10757;11402.828957565644;11996.940015299868;12283.631491055283;753159;755442;762915;774202.1289893514;780322;170140.1492992552;170768.99999999753;173756.18097185405;174466;175726;991.6628346362127;1014.8883622801486;1041.5521325829814;1042.1610603464133;1045.2213101239624;19664.016799160272;19765.40703778889;20061.796292222512;20073;20078.49803754918;55761.442385575625;61292;61342.386576135454;62430.687846560475;62671;84082;93502.53248733713;95072;95668.52165739126;97007.51496242757;7029.613371264425;8323.126071762377;8360.623771930155;8829.205371516595;8830.514321712368;11091.611793587186;12220.45007974661;13141.802872957105;13523.932380338429;13654;10343.327683700836;10495.895041049493;11605.30368177912;11622.172720192011;11719.707007324543;11460.541578337106;11601.477933492844;11607.941960290482;11646.301221926711;11747.588834390755;11385.373804440504;11670.88329116698;11878.940605296919;11880.702982425508;12009.759804804029;7455.179930207564;7635.091227193202;8462;8655.610497527752;8787.648494060166;10824.729381765519;11618.593349233075;11885.881141188478;11890.405479726152;12058.15592908488 04:35:35 INFO - PROCESS | 1645 | |3;mootools.html;1425;1428.7571112910805;1440.4742269071792;1441.7476941535233;1445.7325394801962;7979.840403191933;8449.535275559841;8449.873251901226;8453.788655283606;8470.534120623362;7229.819254518627;7370.815729606763;7645.120811106724;7776.650050747716;8092.757217283476;2204.206485665163;2257.7403598586125;2263.581237471065;2265.988670056656;2269.3645779181775;1987.8967173218857;2162.2756376613775;2284.6977222982905;2293.20884294918;2294.7475777664445;2016.7781544030145;2082.448151239915;2204.856684315522;2240;2261.2198791417018;2246.079107565898;2271.239134889814;2273.5793878132517;2278.2026290798253;2279.179495381661;1981;2174.706049900323;2210.292706333974;2270.0919632147106;2272.7613600571867;8192.303654189402;8266.586670666442;8352.290055345304;8354.955630546181;8357.874631880526;5067.062593420229;5111.05445492585;5223.71269580173;5231.058409486291;5249.658772179796;2936.2365784896083;3205.535197396388;3381.8309084545676;3417.709494692954;3425.7088147507493;1132.3501394676334;1157.8147496400536;1187.780260651782;1203.7472130852443;1207.6256360528178;10433.269671123024;10501.684949451605;10695;10698;10703;4471.562998166008;4590.104929538775;4629.25931850909;4665;4674.742889141062;8659.393842431095;8714.128587141362;8717.825643487095;8723.869141962876;8735.694250701195;5140.742962851842;5142.640015198975;5145.588352931756;5147.948520514822;5165.251038599457;3729.070796890674;4616.838410655678;4743.905121897543;4818.6626936114135;4819.108464933998;3226.951240846734;3624.347617428888;3632.3461776879885;3731.738778285494;3740.4763333133383;1079.3631757263252;1138.510440510572;1140.4183866228284;1149.724066224117;1153.4521102476256;864.3992425264381;878.3982971664431;882.3294296334832;900.4642237868458;922.5202894494826;5251.159814429673;5267.236250743697;5523.143912693539;5683.488486036276;5684.488396044293;546.4890327543759;563.6477201748907;569.6382796923983;576.1357963055417;587.1603606842257;3600.6579374959338;3601.405768048295;3679.282539904693;3693.2798104369926;3827.3684842000757;9438.669646562475;9747.220222382193;9753.609855605839;9974.152197063331;9995.250356223312;9826.80346393068;9844.655437059668;9845.852312215322;9853.655122070693;9856.408615483095;7515.94776731258;7689.38484921205;7722.53664780115;7742.806429839186;7875.291223789772;5496.725163741877;5509.173623956439;5553.666779993213;5576.442355764391;5799.884002320015;7653.311201991847;8419.158084191777;8688.869666955126;8943.150400711922;8961.686340978034;8850.690225842065;8894.822103558023;9130.086991300817;9165.358424910191;9167.724968250697;6445.162128923397;6616.073749674953;6616.172978377703;7028.824279393056;7270.781876543712;7595.5442673439775;7824.84350313002;7835.294823465915;7961.283484486424;7991.3606911448105;6936.479764017819;7079.539829911039;7116.50184487081;7176.7129314826825;7187.2453392393045;6965.686544105426;6997.020417141706;6999.965000174967;7067.187273463617;7078.787636370917;6160.9691951541745;6222.968885155546;6252.218472690914;6320.873582528416;6333.524985626004;4848.781804818862;4893.6085113191575;4917.549322949667;4948.752562371939;4996.275540046746;4644.93032604518;4892.584130348818;4984.277279794482;5004.624653150955;5038.596912246938 04:35:35 INFO - PROCESS | 1645 | |4;prototype.html;352.4079546362111;354.0123056671884;361.6690727983895;370.0914255502741;371.6395096756149;4329.199098166833;5039.697618142914;5106.8978620427615;5357.4374690657505;5433.347998240211;4275.5510671379525;5028.371453568304;5156.421789105448;5166.844994650167;5301.920471192935;3838.827252773618;4198.622124008839;4224.9155016899795;4227.408162857202;4233.179732714932;3824.3116239076953;4077.429159917599;4110.732802367851;4237.703360764748;4260.338467997483;4099.7745124018165;4139.586041395866;4143.212789569972;4166.916661666764;4187.246295666779;3426.7772594781386;3910.1593157470998;3916.3733802591732;4176.929956481983;4185.665146788265;3701.777893326409;3734.159814041846;3734.6452087051957;3748.5126933498473;3749.9062523437133;4271.743695378287;4792.897633544269;5028.586278586263;5161.664491808058;5268.4994925482015;4033.4353190553347;4186.392973018926;4304.417349590252;4563.977180114078;4597.42532183477;4855.805767769285;5559.555235581144;5575.414581468968;5578.525825304854;5593.272874526327;3212.3254116635535;3671.4125739881756;3786.6213378662187;3825.729001703531;3866.3620502617036;4589.908201836011;5696.743646535916;5761.395053519362;5773.855653608652;5791.623544469639;3870.7226615217473;3873.0247573737033;3876.1860009398342;3902.1025164211824;3907.5115610548683;3495.951214635599;3667.0832291927018;3840.9423858642144;3902.6487616114687;3935.9606403935595;3878.437626544135;4486.887827804266;4519.666698324002;4565.5206203348835;4614.676972611885;5560.86097847549;5622.297212848394;5623.887522249532;5624.831255062355;5650.660960342392;1079.2930630437077;1186.5728337798387;1228.3121451986915;1231.5073970411727;1260.7415479826614;749.7450866705346;841.3101256969348;862.2283056664251;865.8916586768946;868.5396739727954;4121.5878412159145;4355.234426139361;4803.687760295569;4868.488808675109;4877.853664390074;4359.934600980988;4741.510706878917;4990.525900039491;5402.432744561843;5411.751774856797;4173.749575025508;4355.324924636687;4522.886927826765;4683.367745354401;4759.309900064472;2444.853308801442;2450.5786643746583;2504.4490212153296;2771.9050974864817;2849.805649649758;4505.152887316184;4533.795979180944;4596.621013695878;5136.563392111638;5302.09864323066;5030.974845125824;5345.625806193607;5439.57938717267;5528.927765462389;5549.611527193058;5232.319798426181;5369.382521010134;5430.15832545956;5593.2101727446825;5598.748056337475;4356.324769660708;4618.722876627413;4662.93005604923;4783;4918.163912135017;4583.166733306704;5031;5306.708131052829;5363.410024897336;5391.460853914734;4949.30709700635;5003.399592048978;5509.421510741475;5549.751305956128;5630.6621602702335;3326.0896715374047;3434.715913917217;3632.4914511968873;3653.1232504199334;3676.761010534307;4706.058788242297;4719.952800472089;5370.36203957781;5425.159100339453;5429.5656347492895;4986.379426686414;5020.849374518771;5137.8715532112;5261.500157485186;5276.577873770012;4494.303382975644;4589.439590539233;4821.252705830602;4861.857463496005;5212.113940630026;4818.518148185293;5147.845564633067;5224.9738751306;5302.39022512416;5369.778133120253;5388.434214407587;5453.61824672285;5506.449355064461;5516.786307012451;5536.7231638417115;5011.34172054395;5036.748162591783;5393.40672526014;5417.295751552273;5440.809571664972 04:35:35 INFO - PROCESS | 1645 | |5;yui.html;702.6065403374114;718.9424846012323;755.9244075592442;800.1558355934488;827.300930713547;14944.80441564675;15133.167675777828;15848.84151158488;15875.444359447421;15888.523344299661;12910.612681619543;13620.591382258523;13627.909767218598;13969.441222351094;14514.274286285696;850.9021462531811;855.9743207703779;870.5734190246765;873.1922971251598;873.6199753107419;850.0819115355432;871.4901782457271;872.145297608341;873.7771868173606;875.7547886591765;921.272194965976;921.7234829551142;922.6078916460511;924.3852837862847;924.6763632728558;886.5877367024333;894.8881389826272;917.2020342302206;917.8944421391559;920.7652048727599;682.754208484945;683.282553319018;684.1209046375384;684.8048306232699;685.4242436353487;12159.635210943687;13558.93220533901;13785.51750688731;15322.23388830554;15599.376024958989;14021.298935053308;14503.1298122113;14583;14641.267936603233;14714.852851471454;11771.58799442024;11957.701057473545;11959.581414650445;11988.973601211876;12169.269843809312;3695.2655035273974;3701.38204829707;3723.925521489555;3799.7530160539477;3831.63599458051;92982.53508732481;93682.53158734231;94669.52665236699;96302.51848740781;98974.0102598972;8992.820143597222;10713.78572428547;10876.184286178568;11273.928976747196;11427.428628568705;23616.64575031376;23833.761662383153;23851;23894.64158037631;23929.7607023931;7680.270374314431;7711.537307761552;7799.5320280783335;7861.921380786119;7975.800604984922;4147.4815648044;4209.726367786084;4303.655707543389;4313.9137217255475;4451.198784218807;3553.8578456861437;3651.7626354286886;3653.6163702811355;3669.8532058717888;3747.5315585551807;694.8262934266434;699.877521433747;701.5475018613068;719.3813320544327;720.2653293640562;662.777969380263;668.2482207516543;700.5411455496658;708.7767353283699;731.9853602928018;9696;10246.897531024744;10391.584336626602;10431.791364172674;10478.63324783629;684.7028220320851;692.3906961873519;699.1016543741268;706.1631966120163;708.4828075504807;4788.867179712093;4908.803647854046;5001.124803159505;5014.774335154927;5024.447310795809;105035.44610092884;105619.94380056101;106110;106916;107615;91556.54221728849;92866.53566732258;93452;93539.66150846283;93990.59014114794;75213.99999999891;79985.71896105428;80492.5975370131;82796;82922.58538707388;9432.528373581155;9644.18024467928;9749.07383798538;9833.5083245836;9847.458389788344;51610.22584661308;52021.21968170556;52494.68763280949;52692;54772.72613636906;54268;69151.96272055822;69177;70433;70825.29174708393;11858.347790871585;11864.288142711464;11917.940410297893;11974.401279936143;12580.182288151242;10560.735981600521;12591.11862169676;12704.36478176106;12803.679908002374;12888.291143987171;11993.580224692443;12014.399280036137;12022.398880055787;12193.695157620776;12495.625131246077;11800.114991375853;11815.645530634094;11970.640880773592;12016.519339226334;12481.25112493217;11924.463399147233;11962.76074478523;11965.581204657796;12349.753004939672;12385.752284954431;9772.40298939352;9921.45432001218;10115.443650599287;10136.898631013595;10463.68608941733;8474.957625211835;9363.625454981997;9669.854952175583;9855.655052073143;9880.604775809175 04:35:35 INFO - PROCESS | 1645 | __end_tp_report 04:35:35 INFO - PROCESS | 1645 | __start_cc_report 04:35:35 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1719 04:35:35 INFO - PROCESS | 1645 | __end_cc_report 04:35:35 INFO - PROCESS | 1645 | __startTimestamp1485866135119__endTimestamp 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | ------- Summary: start ------- 04:35:35 INFO - PROCESS | 1645 | Number of tests: 6 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5692.17 Median:5941.29 stddev:628.27 (10.6%) stddev-sans-first:601.36 04:35:35 INFO - PROCESS | 1645 | Values: 3293.9 3298.5 3315.4 3335.1 3357.3 5514.7 5598.9 5631.6 5640.2 5668.3 5628.8 5653.0 5655.9 5658.5 5665.1 5128.0 5162.5 5171.2 5206.5 5232.3 5268.9 5273.9 5276.2 5295.4 5301.7 5264.7 5269.7 5269.7 5274.4 5276.7 4360.2 5179.7 5247.0 5265.2 5277.8 5010.0 5140.1 5178.5 5234.2 5256.2 5687.5 5736.9 5758.5 5776.4 5780.5 6016.5 6032.2 6218.2 6235.5 6243.8 5153.8 5286.0 6137.8 6149.9 6202.2 4844.9 5864.8 5890.7 5899.1 5928.0 6257.0 6264.3 6272.9 6276.8 6308.0 5249.8 5965.6 5998.7 6058.1 6134.6 6143.7 6175.3 6178.0 6178.1 6178.5 6023.5 6143.4 6144.3 6150.3 6168.0 6085.9 6108.5 6137.1 6143.4 6163.5 4988.2 5025.4 5029.4 5039.5 5081.6 4449.5 4456.9 4457.1 4471.0 4491.8 5561.1 5794.0 5799.2 5816.2 5822.4 6041.1 6060.5 6087.1 6109.2 6132.8 5473.2 5493.6 5519.4 5534.3 5570.0 5268.2 5390.9 5393.1 5406.5 5435.2 6208.9 6272.1 6282.0 6284.4 6297.6 6108.9 6117.9 6119.7 6135.8 6189.4 6151.3 6275.2 6289.5 6298.1 6300.7 5997.0 6166.3 6174.4 6178.0 6192.9 6115.7 6139.2 6227.3 6229.5 6250.4 6076.7 6092.2 6112.2 6112.9 6140.8 6051.5 6086.9 6098.5 6101.2 6115.6 5663.1 5741.0 5852.7 5922.1 5941.3 6086.2 6088.9 6124.1 6129.8 6133.0 5951.9 5958.8 5960.4 5965.3 6013.9 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:67701.23 Median:38962.01 stddev:143356.28 (367.9%) stddev-sans-first:143669.48 04:35:35 INFO - PROCESS | 1645 | Values: 440.6 497.5 501.6 503.2 503.4 40750.4 42589.6 42717.8 42812.8 44479.6 42243.8 42573.9 42635.6 42715.0 42737.4 8511.0 9363.1 9565.6 9579.7 9585.3 8461.2 11319.5 11624.8 11647.5 11866.3 11601.0 11601.8 11654.8 11679.9 11694.4 8553.3 11687.6 11757.8 11770.3 11813.0 8539.7 11201.0 11488.9 11492.5 11776.9 41916.6 42127.0 42276.6 42326.0 42385.6 4025.0 4029.3 4035.3 4079.3 4167.4 31422.2 31423.1 31423.8 31458.5 31502.8 2908.3 3044.6 3114.6 3146.2 3146.2 464823.0 466187.0 469069.0 472250.6 476986.0 52576.5 59271.8 59595.8 59607.0 59669.0 63758.4 63805.7 63812.7 64055.0 64166.0 16075.5 16332.7 16348.2 16358.7 16396.8 11261.2 11604.8 12633.9 12673.3 12711.5 13864.7 15506.8 15876.9 15937.1 16263.6 6148.7 6407.9 6446.6 6782.5 6862.4 858.5 865.7 872.7 876.4 903.4 29679.6 29985.6 30020.4 30556.4 30697.8 976.7 1041.7 1073.4 1079.7 1082.0 15136.8 15478.6 15533.8 15711.2 15999.4 610348.0 617400.0 617617.0 626332.0 626550.0 57386.7 57476.4 57544.8 57619.7 57634.0 1407.0 1411.7 1414.3 1417.6 1438.0 28614.4 28880.0 29286.7 29389.8 30202.4 49334.8 49726.0 49772.0 49780.0 49853.8 511499.0 512591.0 512882.0 513531.0 515296.0 38689.8 38822.6 39101.4 39275.4 39900.8 44913.6 45531.9 45617.3 46471.8 46474.8 33067.0 37848.8 38143.2 38272.6 39451.4 36309.6 36451.5 37873.8 38729.4 39119.8 37413.4 37573.4 37848.0 38015.8 38181.0 31991.7 33510.3 33628.8 34143.7 34686.5 22559.1 24543.5 24958.5 25186.9 25224.9 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:43216.07 Median:21667.39 stddev:128129.19 (591.3%) stddev-sans-first:128448.19 04:35:35 INFO - PROCESS | 1645 | Values: 345.5 376.1 394.3 397.6 428.9 15122.0 16480.8 20014.3 20683.0 21638.7 20882.7 22012.8 22032.2 22298.3 22445.9 5762.9 6123.6 6129.0 6132.2 6141.1 4593.2 6049.6 6076.1 6090.5 6093.3 5759.6 6045.8 6056.3 6064.2 6065.8 5871.0 5903.4 5912.3 5917.3 6018.1 4438.3 5057.2 5135.7 5168.3 5216.1 26148.5 26256.6 26273.0 26283.7 26558.0 2449.6 2605.1 2664.8 2706.1 2727.4 10172.9 10193.1 10207.6 10558.4 10643.4 1755.6 1810.5 1810.6 1813.6 1815.9 145644.7 147366.0 148325.0 148529.0 151544.0 10898.2 11649.4 11866.6 11868.5 12066.1 14516.1 15001.6 35667.0 36920.3 39535.0 7851.0 10925.7 11394.3 11501.4 11509.0 6909.4 8290.2 8858.0 8951.6 9079.5 8652.9 10342.2 10797.6 10869.0 10872.0 3374.5 3450.6 3959.5 4028.6 4093.0 1012.3 1012.7 1018.1 1024.4 1051.0 21307.6 21665.8 21669.0 21964.2 22105.8 937.1 1016.2 1028.9 1045.6 1049.8 10148.0 10757.0 11402.8 11996.9 12283.6 753159.0 755442.0 762915.0 774202.1 780322.0 170140.1 170769.0 173756.2 174466.0 175726.0 991.7 1014.9 1041.6 1042.2 1045.2 19664.0 19765.4 20061.8 20073.0 20078.5 55761.4 61292.0 61342.4 62430.7 62671.0 84082.0 93502.5 95072.0 95668.5 97007.5 7029.6 8323.1 8360.6 8829.2 8830.5 11091.6 12220.5 13141.8 13523.9 13654.0 10343.3 10495.9 11605.3 11622.2 11719.7 11460.5 11601.5 11607.9 11646.3 11747.6 11385.4 11670.9 11878.9 11880.7 12009.8 7455.2 7635.1 8462.0 8655.6 8787.6 10824.7 11618.6 11885.9 11890.4 12058.2 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5298.09 Median:5259.20 stddev:2900.86 (55.2%) stddev-sans-first:2894.39 04:35:35 INFO - PROCESS | 1645 | Values: 1425.0 1428.8 1440.5 1441.7 1445.7 7979.8 8449.5 8449.9 8453.8 8470.5 7229.8 7370.8 7645.1 7776.7 8092.8 2204.2 2257.7 2263.6 2266.0 2269.4 1987.9 2162.3 2284.7 2293.2 2294.7 2016.8 2082.4 2204.9 2240.0 2261.2 2246.1 2271.2 2273.6 2278.2 2279.2 1981.0 2174.7 2210.3 2270.1 2272.8 8192.3 8266.6 8352.3 8355.0 8357.9 5067.1 5111.1 5223.7 5231.1 5249.7 2936.2 3205.5 3381.8 3417.7 3425.7 1132.4 1157.8 1187.8 1203.7 1207.6 10433.3 10501.7 10695.0 10698.0 10703.0 4471.6 4590.1 4629.3 4665.0 4674.7 8659.4 8714.1 8717.8 8723.9 8735.7 5140.7 5142.6 5145.6 5147.9 5165.3 3729.1 4616.8 4743.9 4818.7 4819.1 3227.0 3624.3 3632.3 3731.7 3740.5 1079.4 1138.5 1140.4 1149.7 1153.5 864.4 878.4 882.3 900.5 922.5 5251.2 5267.2 5523.1 5683.5 5684.5 546.5 563.6 569.6 576.1 587.2 3600.7 3601.4 3679.3 3693.3 3827.4 9438.7 9747.2 9753.6 9974.2 9995.3 9826.8 9844.7 9845.9 9853.7 9856.4 7515.9 7689.4 7722.5 7742.8 7875.3 5496.7 5509.2 5553.7 5576.4 5799.9 7653.3 8419.2 8688.9 8943.2 8961.7 8850.7 8894.8 9130.1 9165.4 9167.7 6445.2 6616.1 6616.2 7028.8 7270.8 7595.5 7824.8 7835.3 7961.3 7991.4 6936.5 7079.5 7116.5 7176.7 7187.2 6965.7 6997.0 7000.0 7067.2 7078.8 6161.0 6223.0 6252.2 6320.9 6333.5 4848.8 4893.6 4917.5 4948.8 4996.3 4644.9 4892.6 4984.3 5004.6 5038.6 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4333.91 Median:4713.01 stddev:1292.91 (27.4%) stddev-sans-first:1261.53 04:35:35 INFO - PROCESS | 1645 | Values: 352.4 354.0 361.7 370.1 371.6 4329.2 5039.7 5106.9 5357.4 5433.3 4275.6 5028.4 5156.4 5166.8 5301.9 3838.8 4198.6 4224.9 4227.4 4233.2 3824.3 4077.4 4110.7 4237.7 4260.3 4099.8 4139.6 4143.2 4166.9 4187.2 3426.8 3910.2 3916.4 4176.9 4185.7 3701.8 3734.2 3734.6 3748.5 3749.9 4271.7 4792.9 5028.6 5161.7 5268.5 4033.4 4186.4 4304.4 4564.0 4597.4 4855.8 5559.6 5575.4 5578.5 5593.3 3212.3 3671.4 3786.6 3825.7 3866.4 4589.9 5696.7 5761.4 5773.9 5791.6 3870.7 3873.0 3876.2 3902.1 3907.5 3496.0 3667.1 3840.9 3902.6 3936.0 3878.4 4486.9 4519.7 4565.5 4614.7 5560.9 5622.3 5623.9 5624.8 5650.7 1079.3 1186.6 1228.3 1231.5 1260.7 749.7 841.3 862.2 865.9 868.5 4121.6 4355.2 4803.7 4868.5 4877.9 4359.9 4741.5 4990.5 5402.4 5411.8 4173.7 4355.3 4522.9 4683.4 4759.3 2444.9 2450.6 2504.4 2771.9 2849.8 4505.2 4533.8 4596.6 5136.6 5302.1 5031.0 5345.6 5439.6 5528.9 5549.6 5232.3 5369.4 5430.2 5593.2 5598.7 4356.3 4618.7 4662.9 4783.0 4918.2 4583.2 5031.0 5306.7 5363.4 5391.5 4949.3 5003.4 5509.4 5549.8 5630.7 3326.1 3434.7 3632.5 3653.1 3676.8 4706.1 4720.0 5370.4 5425.2 5429.6 4986.4 5020.8 5137.9 5261.5 5276.6 4494.3 4589.4 4821.3 4861.9 5212.1 4818.5 5147.8 5225.0 5302.4 5369.8 5388.4 5453.6 5506.4 5516.8 5536.7 5011.3 5036.7 5393.4 5417.3 5440.8 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20288.65 Median:28317.34 stddev:29374.09 (103.7%) stddev-sans-first:29419.68 04:35:35 INFO - PROCESS | 1645 | Values: 702.6 718.9 755.9 800.2 827.3 14944.8 15133.2 15848.8 15875.4 15888.5 12910.6 13620.6 13627.9 13969.4 14514.3 850.9 856.0 870.6 873.2 873.6 850.1 871.5 872.1 873.8 875.8 921.3 921.7 922.6 924.4 924.7 886.6 894.9 917.2 917.9 920.8 682.8 683.3 684.1 684.8 685.4 12159.6 13558.9 13785.5 15322.2 15599.4 14021.3 14503.1 14583.0 14641.3 14714.9 11771.6 11957.7 11959.6 11989.0 12169.3 3695.3 3701.4 3723.9 3799.8 3831.6 92982.5 93682.5 94669.5 96302.5 98974.0 8992.8 10713.8 10876.2 11273.9 11427.4 23616.6 23833.8 23851.0 23894.6 23929.8 7680.3 7711.5 7799.5 7861.9 7975.8 4147.5 4209.7 4303.7 4313.9 4451.2 3553.9 3651.8 3653.6 3669.9 3747.5 694.8 699.9 701.5 719.4 720.3 662.8 668.2 700.5 708.8 732.0 9696.0 10246.9 10391.6 10431.8 10478.6 684.7 692.4 699.1 706.2 708.5 4788.9 4908.8 5001.1 5014.8 5024.4 105035.4 105619.9 106110.0 106916.0 107615.0 91556.5 92866.5 93452.0 93539.7 93990.6 75214.0 79985.7 80492.6 82796.0 82922.6 9432.5 9644.2 9749.1 9833.5 9847.5 51610.2 52021.2 52494.7 52692.0 54772.7 54268.0 69152.0 69177.0 70433.0 70825.3 11858.3 11864.3 11917.9 11974.4 12580.2 10560.7 12591.1 12704.4 12803.7 12888.3 11993.6 12014.4 12022.4 12193.7 12495.6 11800.1 11815.6 11970.6 12016.5 12481.3 11924.5 11962.8 11965.6 12349.8 12385.8 9772.4 9921.5 10115.4 10136.9 10463.7 8475.0 9363.6 9669.9 9855.7 9880.6 04:35:35 INFO - PROCESS | 1645 | -------- Summary: end -------- 04:35:35 INFO - PROCESS | 1645 | 04:35:35 INFO - TEST-INFO | 1645: exit 0 04:35:35 INFO - TEST-OK | dromaeo_css | took 1119820ms 04:35:35 INFO - TEST-START | kraken 04:35:35 INFO - operating with platform_type : mac_ 04:35:35 INFO - Initialising browser for kraken test... 04:35:35 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpxe1YYc/profile http://localhost:49243/getInfo.html) 04:35:37 INFO - PROCESS | 1653 | 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] 04:35:38 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 04:35:38 INFO - PROCESS | 1653 | colorDepth:24 04:35:38 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 04:35:38 INFO - PROCESS | 1653 | __metrics 04:35:38 INFO - PROCESS | 1653 | 1485866138332 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/FirefoxNightly.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/FirefoxNightly.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 04:35:38 INFO - PROCESS | 1653 | 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" 04:35:38 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:35:39 INFO - TEST-INFO | 1653: exit 0 04:35:39 INFO - Browser initialized. 04:35:39 INFO - Running cycle 1/1 for kraken test... 04:35:39 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpxe1YYc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:51 INFO - PROCESS | 1655 | RSS: Main: 236908544 04:35:51 INFO - PROCESS | 1655 | 04:35:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:54 INFO - PROCESS | 1655 | RSS: Main: 289042432 04:35:54 INFO - PROCESS | 1655 | 04:35:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 04:35:58 INFO - PROCESS | 1655 | RSS: Main: 290533376 04:35:58 INFO - PROCESS | 1655 | 04:36:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 04:36:02 INFO - PROCESS | 1655 | RSS: Main: 379486208 04:36:02 INFO - PROCESS | 1655 | 04:36:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 04:36:04 INFO - PROCESS | 1655 | RSS: Main: 284012544 04:36:04 INFO - PROCESS | 1655 | 04:36:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:36:07 INFO - PROCESS | 1655 | RSS: Main: 286814208 04:36:07 INFO - PROCESS | 1655 | 04:36:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 04:36:10 INFO - PROCESS | 1655 | RSS: Main: 329789440 04:36:10 INFO - PROCESS | 1655 | 04:36:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 04:36:14 INFO - PROCESS | 1655 | RSS: Main: 332939264 04:36:14 INFO - PROCESS | 1655 | 04:36:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 04:36:17 INFO - PROCESS | 1655 | RSS: Main: 327663616 04:36:17 INFO - PROCESS | 1655 | 04:36:19 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:36:19 INFO - PROCESS | 1655 | RSS: Main: 305553408 04:36:19 INFO - PROCESS | 1655 | 04:36:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:36:22 INFO - PROCESS | 1655 | RSS: Main: 308903936 04:36:22 INFO - PROCESS | 1655 | 04:36:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:25 INFO - PROCESS | 1655 | RSS: Main: 310669312 04:36:25 INFO - PROCESS | 1655 | 04:36:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:29 INFO - PROCESS | 1655 | RSS: Main: 328953856 04:36:29 INFO - PROCESS | 1655 | 04:36:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:32 INFO - PROCESS | 1655 | RSS: Main: 322920448 04:36:32 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 04:36:34 INFO - PROCESS | 1655 | RSS: Main: 367882240 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | __start_tp_report 04:36:34 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 04:36:34 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 04:36:34 INFO - PROCESS | 1655 | |i|pagename|runs| 04:36:34 INFO - PROCESS | 1655 | |0;ai-astar;112;113;113;112;113;113;113;113;113;112 04:36:34 INFO - PROCESS | 1655 | |1;audio-beat-detection;144;98;112;171;143;110;109;107;128;109 04:36:34 INFO - PROCESS | 1655 | |2;audio-dft;182;183;184;185;185;184;178;183;184;183 04:36:34 INFO - PROCESS | 1655 | |3;audio-fft;79;60;75;110;81;69;59;59;76;58 04:36:34 INFO - PROCESS | 1655 | |4;audio-oscillator;91;70;87;71;68;69;83;69;69;82 04:36:34 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;87;91;88;90;88;91;88;90;87 04:36:34 INFO - PROCESS | 1655 | |6;imaging-darkroom;99;99;99;99;99;99;97;99;99;99 04:36:34 INFO - PROCESS | 1655 | |7;imaging-desaturate;88;86;87;87;89;86;87;85;84;82 04:36:34 INFO - PROCESS | 1655 | |8;json-parse-financial;61;57;58;57;59;59;60;59;59;58 04:36:34 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;76;71;69;79;74;70;72;71;72;73 04:36:34 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;89;90;87;87;86;86;88;87;89 04:36:34 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;140;142;145;151;158;140;140;142;179;145 04:36:34 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;174;176;175;174;174;174;176;175;175;176 04:36:34 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;68;68;67;67;67;67;66;67;66 04:36:34 INFO - PROCESS | 1655 | __end_tp_report 04:36:34 INFO - PROCESS | 1655 | __start_cc_report 04:36:34 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2003 04:36:34 INFO - PROCESS | 1655 | __end_cc_report 04:36:34 INFO - PROCESS | 1655 | __startTimestamp1485866194585__endTimestamp 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | ------- Summary: start ------- 04:36:34 INFO - PROCESS | 1655 | Number of tests: 14 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:36:34 INFO - PROCESS | 1655 | Values: 112.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 112.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:135.50 stddev:22.93 (16.9%) stddev-sans-first:23.04 04:36:34 INFO - PROCESS | 1655 | Values: 144.0 98.0 112.0 171.0 143.0 110.0 109.0 107.0 128.0 109.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:2.02 (1.1%) stddev-sans-first:2.11 04:36:34 INFO - PROCESS | 1655 | Values: 182.0 183.0 184.0 185.0 185.0 184.0 178.0 183.0 184.0 183.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:72.60 Median:72.00 stddev:15.92 (22.1%) stddev-sans-first:16.72 04:36:34 INFO - PROCESS | 1655 | Values: 79.0 60.0 75.0 110.0 81.0 69.0 59.0 59.0 76.0 58.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:76.50 stddev:8.84 (11.6%) stddev-sans-first:7.50 04:36:34 INFO - PROCESS | 1655 | Values: 91.0 70.0 87.0 71.0 68.0 69.0 83.0 69.0 69.0 82.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:1.56 (1.7%) stddev-sans-first:1.62 04:36:34 INFO - PROCESS | 1655 | Values: 90.0 87.0 91.0 88.0 90.0 88.0 91.0 88.0 90.0 87.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:98.80 Median:99.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 04:36:34 INFO - PROCESS | 1655 | Values: 99.0 99.0 99.0 99.0 99.0 99.0 97.0 99.0 99.0 99.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:87.00 stddev:2.02 (2.3%) stddev-sans-first:2.03 04:36:34 INFO - PROCESS | 1655 | Values: 88.0 86.0 87.0 87.0 89.0 86.0 87.0 85.0 84.0 82.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:1.25 (2.1%) stddev-sans-first:1.01 04:36:34 INFO - PROCESS | 1655 | Values: 61.0 57.0 58.0 57.0 59.0 59.0 60.0 59.0 59.0 58.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:72.50 stddev:2.98 (4.1%) stddev-sans-first:2.92 04:36:34 INFO - PROCESS | 1655 | Values: 76.0 71.0 69.0 79.0 74.0 70.0 72.0 71.0 72.0 73.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.50 stddev:1.43 (1.6%) stddev-sans-first:1.41 04:36:34 INFO - PROCESS | 1655 | Values: 86.0 89.0 90.0 87.0 87.0 86.0 86.0 88.0 87.0 89.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:145.00 stddev:12.25 (8.5%) stddev-sans-first:12.63 04:36:34 INFO - PROCESS | 1655 | Values: 140.0 142.0 145.0 151.0 158.0 140.0 140.0 142.0 179.0 145.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:175.00 stddev:0.88 (0.5%) stddev-sans-first:0.87 04:36:34 INFO - PROCESS | 1655 | Values: 174.0 176.0 175.0 174.0 174.0 174.0 176.0 175.0 175.0 176.0 04:36:34 INFO - PROCESS | 1655 | 04:36:34 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 04:36:34 INFO - PROCESS | 1655 | Values: 68.0 68.0 68.0 67.0 67.0 67.0 67.0 66.0 67.0 66.0 04:36:34 INFO - PROCESS | 1655 | -------- Summary: end -------- 04:36:34 INFO - PROCESS | 1655 | 04:36:35 INFO - TEST-INFO | 1655: exit 0 04:36:35 INFO - TEST-OK | kraken | took 59747ms 04:36:35 INFO - SUITE-END | took 1179s 04:36:35 INFO - Completed test suite (00:19:39) 04:36:35 INFO - javascript benchmark 04:36:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7308.9285758437845, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.851776670051, 3298.5052242163674, 3315.4032274190645, 3335.1495368680985, 3357.328534293142, 5514.6691198528115, 5598.860028499284, 5631.57763167762, 5640.1821735848325, 5668.319801623811, 5628.802991895274, 5653.038983372826, 5655.943440565593, 5658.462446067627, 5665.12190610455, 5127.974360128194, 5162.5095615916425, 5171.198464238049, 5206.45332240115, 5232.3459567554055, 5268.92096618551, 5273.8945221095555, 5276.234945932837, 5295.391030031538, 5301.6818990860675, 5264.657797243186, 5269.65747226431, 5269.683818970855, 5274.4198138204765, 5276.736163191844, 4360.171567402215, 5179.741012949337, 5246.973765131169, 5265.157574788034, 5277.762500687479, 5009.974950125263, 5140.126178549618, 5178.533931946105, 5234.18870075139, 5256.159014557691, 5687.544996400278, 5736.8852622947725, 5758.539316854642, 5776.422357764232, 5780.53755699543, 6016.4885984691355, 6032.245969253843, 6218.22272215973, 6235.501159907242, 6243.7814676486105, 5153.819616313411, 5285.973570132163, 6137.836677219332, 6149.907751383733, 6202.193714817045, 4844.927326090112, 5864.794732184353, 5890.705464726832, 5899.085641725539, 5928.02187639039, 6256.968715156395, 6264.342244064399, 6272.905906411407, 6276.843078923063, 6307.96846015767, 5249.792547713971, 5965.55258355625, 5998.730057147439, 6058.0610005449225, 6134.6319220846, 6143.696113957266, 6175.2898416681755, 6178.011518157074, 6178.135061091451, 6178.505719542426, 6023.548233882477, 6143.354947730513, 6144.324124346318, 6150.292716337589, 6167.982282923357, 6085.878282434326, 6108.463491842691, 6137.140800287963, 6143.416375444325, 6163.476104531075, 4988.176950803077, 5025.3718285214345, 5029.421616514148, 5039.496050395004, 5081.59347252219, 4449.466064072268, 4456.9331460028125, 4457.108578284357, 4470.971676514355, 4491.775411229425, 5561.138023606267, 5794.0, 5799.217105690762, 5816.2148110005455, 5822.388649191858, 6041.124037014696, 6060.515158787286, 6087.1173679816175, 6109.16175461965, 6132.754689812357, 5473.17902314648, 5493.587980901449, 5519.39286678465, 5534.335879694462, 5569.972150139304, 5268.157094864803, 5390.946090539122, 5393.083175860034, 5406.486383793534, 5435.157550579587, 6208.906866397098, 6272.09054687077, 6281.968590157111, 6284.402981716729, 6297.559170857996, 6108.938910610837, 6117.9187529241, 6119.654885717031, 6135.785247516315, 6189.35011823752, 6151.2618485781995, 6275.215598050244, 6289.49684025286, 6298.0552917062805, 6300.684965751602, 5997.010493268562, 6166.321704612399, 6174.413430724074, 6178.011518157253, 6192.907106393318, 6115.696722738907, 6139.154842350657, 6227.314995350598, 6229.50163986889, 6250.374962503895, 6076.72654730547, 6092.177556029879, 6112.17485639433, 6112.938870611415, 6140.785072522441, 6051.455369016633, 6086.908696369646, 6098.542609304409, 6101.176341193882, 6115.571909966259, 5663.122216056518, 5740.971295143665, 5852.707364631837, 5922.052471604523, 5941.287045554561, 6086.2087928569, 6088.939110608837, 6124.075808605272, 6129.754809807558, 6132.998044146438, 5951.940480595139, 5958.791442299672, 5960.403959604005, 5965.34381218057, 6013.909791353046], "value": 5652.993672943121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.5770460358056, 497.48261807719973, 501.56614528432914, 503.1873524258323, 503.3758139906516, 40750.38874416883, 42589.57410425899, 42717.78641106798, 42812.78593607035, 44479.55520444795, 42243.78878105613, 42573.93565160873, 42635.573644263546, 42715.0, 42737.35893961593, 8510.957445212765, 9363.063693630616, 9565.569549370295, 9579.712608621718, 9585.2811039172, 8461.15388461155, 11319.490622921947, 11624.825627615592, 11647.534098636044, 11866.288022718622, 11601.013913817336, 11601.825972610417, 11654.766904661901, 11679.941600291986, 11694.415279236047, 8553.272971797405, 11687.5909343173, 11757.764844703144, 11770.293782373043, 11813.0, 8539.701110461167, 11201.0, 11488.885111148866, 11492.540298388054, 11776.882231177664, 41916.580834191875, 42127.0, 42276.57723422788, 42326.0, 42385.57614423847, 4025.0339918419663, 4029.3351596986463, 4035.2938235808615, 4079.3269110596716, 4167.354060120686, 31422.214444638837, 31423.05730828079, 31423.84288078568, 31458.528122077958, 31502.842485787653, 2908.2729317670583, 3044.5737596736476, 3114.6262448506322, 3146.197719581515, 3146.2449012237125, 464823.0, 466187.0, 469069.0, 472250.6387468041, 476986.0, 52576.47423525792, 59271.81456370935, 59595.80808383895, 59607.0, 59669.0, 63758.406039849375, 63805.68097159577, 63812.72374552483, 64055.0, 64166.0, 16075.517734467985, 16332.67334653324, 16348.182590870409, 16358.672826543641, 16396.75404868928, 11261.155413344031, 11604.825927611093, 12633.873661263453, 12673.302968336646, 12711.491540338282, 13864.653383665489, 15506.767398489032, 15876.856624355794, 15937.123458209679, 16263.593410164603, 6148.723307451175, 6407.903881441782, 6446.64851225421, 6782.457403407814, 6862.35799217305, 858.5449711652834, 865.7359505350803, 872.6553011560399, 876.369014309696, 903.3902116071625, 29679.554806677486, 29985.550216746768, 30020.399592008038, 30556.388872222433, 30697.84651076775, 976.72651657536, 1041.7083216699336, 1073.3881687438086, 1079.6868908016745, 1081.9729506762394, 15136.805163251267, 15478.613034673997, 15533.844661553467, 15711.171989041854, 15999.360025599079, 610348.0, 617400.0, 617617.0, 626332.0, 626550.0, 57386.71306643524, 57476.42523574794, 57544.8491030177, 57619.711901441064, 57634.0, 1407.029149886575, 1411.745885740556, 1414.285785678226, 1417.574727581742, 1438.0077746354982, 28614.42771144607, 28880.0, 29286.678330417395, 29389.824407024335, 30202.395952081275, 49334.75332623314, 49726.0, 49772.00455990932, 49780.0, 49853.750731247565, 511499.0, 512591.0, 512882.0, 513531.0, 515296.0, 38689.80655096706, 38822.61177388303, 39101.41347879727, 39275.41086883756, 39900.80049599733, 44913.550864490935, 45531.86170345768, 45617.31574026459, 46471.767641161576, 46474.76762616165, 33067.0, 37848.81075594604, 38143.237135257696, 38272.61727382691, 39451.40822887602, 36309.553566965165, 36451.45322820107, 37873.81063094667, 38729.41905871358, 39119.80440097781, 37413.438798417505, 37573.4363984535, 37848.0, 38015.80992095022, 38181.0, 31991.68008319887, 33510.32979340448, 33628.80280148853, 34143.65856341405, 34686.47970280498, 22559.09763609437, 24543.50912981766, 24958.5150885836, 25186.874065629556, 25224.873875630503], "value": 20187.631696447344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.4990264117029, 376.1255081934503, 394.2902775004992, 397.6361629109365, 428.88634511854366, 15122.0, 16480.752788708178, 20014.29949951752, 20683.0, 21638.675419868716, 20882.686759698616, 22012.79872012798, 22032.228871989482, 22298.331050068482, 22445.887770561123, 5762.9135562966485, 6123.632582045091, 6129.019356902896, 6132.2028136342215, 6141.078838174287, 4593.173228818811, 6049.606775559597, 6076.118962750398, 6090.451859332659, 6093.268807743077, 5759.568032397566, 6045.818625441244, 6056.303525094627, 6064.211652485171, 6065.787697430591, 5870.970645146747, 5903.35063143054, 5912.290525136968, 5917.319508256563, 6018.097285407181, 4438.289873620238, 5057.190849464068, 5135.717535535544, 5168.302279192299, 5216.139337009389, 26148.477030459475, 26256.555889426076, 26273.0, 26283.73716262832, 26558.0, 2449.6448015037718, 2605.1142611511987, 2664.760171584547, 2706.1340371081064, 2727.372704278027, 10172.94913525435, 10193.133583645398, 10207.62197103389, 10558.366498010144, 10643.361398316125, 1755.6049888774924, 1810.4840120565575, 1810.6107186954737, 1813.6010077782867, 1815.9182836772245, 145644.68639242506, 147366.0, 148325.0, 148529.0, 151544.0, 10898.237123401444, 11649.359285239223, 11866.584669536525, 11868.465919033664, 12066.113224144236, 14516.129032258099, 15001.624959375884, 35667.0, 36920.26159476795, 39535.0, 7851.0, 10925.672229833117, 11394.25937314072, 11501.42492875353, 11508.98592746266, 6909.412699920563, 8290.170982901662, 8858.0, 8951.552422378854, 9079.546022698838, 8652.923298408463, 10342.172626189888, 10797.62208322705, 10869.021788039114, 10872.0, 3374.5320785458402, 3450.551428314303, 3959.4852669152824, 4028.5971402860064, 4092.9795351023054, 1012.3268026762267, 1012.71643939697, 1018.0938964321759, 1024.416082832793, 1051.038299955532, 21307.57384852294, 21665.78334216669, 21669.0, 21964.231251906425, 22105.778942210694, 937.1378331934636, 1016.1718199667248, 1028.9022542858415, 1045.6078970386104, 1049.7900419916048, 10148.0, 10757.0, 11402.828957565644, 11996.940015299868, 12283.631491055283, 753159.0, 755442.0, 762915.0, 774202.1289893514, 780322.0, 170140.1492992552, 170768.99999999753, 173756.18097185405, 174466.0, 175726.0, 991.6628346362127, 1014.8883622801486, 1041.5521325829814, 1042.1610603464133, 1045.2213101239624, 19664.016799160272, 19765.40703778889, 20061.796292222512, 20073.0, 20078.49803754918, 55761.442385575625, 61292.0, 61342.386576135454, 62430.687846560475, 62671.0, 84082.0, 93502.53248733713, 95072.0, 95668.52165739126, 97007.51496242757, 7029.613371264425, 8323.126071762377, 8360.623771930155, 8829.205371516595, 8830.514321712368, 11091.611793587186, 12220.45007974661, 13141.802872957105, 13523.932380338429, 13654.0, 10343.327683700836, 10495.895041049493, 11605.30368177912, 11622.172720192011, 11719.707007324543, 11460.541578337106, 11601.477933492844, 11607.941960290482, 11646.301221926711, 11747.588834390755, 11385.373804440504, 11670.88329116698, 11878.940605296919, 11880.702982425508, 12009.759804804029, 7455.179930207564, 7635.091227193202, 8462.0, 8655.610497527752, 8787.648494060166, 10824.729381765519, 11618.593349233075, 11885.881141188478, 11890.405479726152, 12058.15592908488], "value": 10745.110928937967, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.0, 1428.7571112910805, 1440.4742269071792, 1441.7476941535233, 1445.7325394801962, 7979.840403191933, 8449.535275559841, 8449.873251901226, 8453.788655283606, 8470.534120623362, 7229.819254518627, 7370.815729606763, 7645.120811106724, 7776.650050747716, 8092.757217283476, 2204.206485665163, 2257.7403598586125, 2263.581237471065, 2265.988670056656, 2269.3645779181775, 1987.8967173218857, 2162.2756376613775, 2284.6977222982905, 2293.20884294918, 2294.7475777664445, 2016.7781544030145, 2082.448151239915, 2204.856684315522, 2240.0, 2261.2198791417018, 2246.079107565898, 2271.239134889814, 2273.5793878132517, 2278.2026290798253, 2279.179495381661, 1981.0, 2174.706049900323, 2210.292706333974, 2270.0919632147106, 2272.7613600571867, 8192.303654189402, 8266.586670666442, 8352.290055345304, 8354.955630546181, 8357.874631880526, 5067.062593420229, 5111.05445492585, 5223.71269580173, 5231.058409486291, 5249.658772179796, 2936.2365784896083, 3205.535197396388, 3381.8309084545676, 3417.709494692954, 3425.7088147507493, 1132.3501394676334, 1157.8147496400536, 1187.780260651782, 1203.7472130852443, 1207.6256360528178, 10433.269671123024, 10501.684949451605, 10695.0, 10698.0, 10703.0, 4471.562998166008, 4590.104929538775, 4629.25931850909, 4665.0, 4674.742889141062, 8659.393842431095, 8714.128587141362, 8717.825643487095, 8723.869141962876, 8735.694250701195, 5140.742962851842, 5142.640015198975, 5145.588352931756, 5147.948520514822, 5165.251038599457, 3729.070796890674, 4616.838410655678, 4743.905121897543, 4818.6626936114135, 4819.108464933998, 3226.951240846734, 3624.347617428888, 3632.3461776879885, 3731.738778285494, 3740.4763333133383, 1079.3631757263252, 1138.510440510572, 1140.4183866228284, 1149.724066224117, 1153.4521102476256, 864.3992425264381, 878.3982971664431, 882.3294296334832, 900.4642237868458, 922.5202894494826, 5251.159814429673, 5267.236250743697, 5523.143912693539, 5683.488486036276, 5684.488396044293, 546.4890327543759, 563.6477201748907, 569.6382796923983, 576.1357963055417, 587.1603606842257, 3600.6579374959338, 3601.405768048295, 3679.282539904693, 3693.2798104369926, 3827.3684842000757, 9438.669646562475, 9747.220222382193, 9753.609855605839, 9974.152197063331, 9995.250356223312, 9826.80346393068, 9844.655437059668, 9845.852312215322, 9853.655122070693, 9856.408615483095, 7515.94776731258, 7689.38484921205, 7722.53664780115, 7742.806429839186, 7875.291223789772, 5496.725163741877, 5509.173623956439, 5553.666779993213, 5576.442355764391, 5799.884002320015, 7653.311201991847, 8419.158084191777, 8688.869666955126, 8943.150400711922, 8961.686340978034, 8850.690225842065, 8894.822103558023, 9130.086991300817, 9165.358424910191, 9167.724968250697, 6445.162128923397, 6616.073749674953, 6616.172978377703, 7028.824279393056, 7270.781876543712, 7595.5442673439775, 7824.84350313002, 7835.294823465915, 7961.283484486424, 7991.3606911448105, 6936.479764017819, 7079.539829911039, 7116.50184487081, 7176.7129314826825, 7187.2453392393045, 6965.686544105426, 6997.020417141706, 6999.965000174967, 7067.187273463617, 7078.787636370917, 6160.9691951541745, 6222.968885155546, 6252.218472690914, 6320.873582528416, 6333.524985626004, 4848.781804818862, 4893.6085113191575, 4917.549322949667, 4948.752562371939, 4996.275540046746, 4644.93032604518, 4892.584130348818, 4984.277279794482, 5004.624653150955, 5038.596912246938], "value": 4253.1286901256135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.4079546362111, 354.0123056671884, 361.6690727983895, 370.0914255502741, 371.6395096756149, 4329.199098166833, 5039.697618142914, 5106.8978620427615, 5357.4374690657505, 5433.347998240211, 4275.5510671379525, 5028.371453568304, 5156.421789105448, 5166.844994650167, 5301.920471192935, 3838.827252773618, 4198.622124008839, 4224.9155016899795, 4227.408162857202, 4233.179732714932, 3824.3116239076953, 4077.429159917599, 4110.732802367851, 4237.703360764748, 4260.338467997483, 4099.7745124018165, 4139.586041395866, 4143.212789569972, 4166.916661666764, 4187.246295666779, 3426.7772594781386, 3910.1593157470998, 3916.3733802591732, 4176.929956481983, 4185.665146788265, 3701.777893326409, 3734.159814041846, 3734.6452087051957, 3748.5126933498473, 3749.9062523437133, 4271.743695378287, 4792.897633544269, 5028.586278586263, 5161.664491808058, 5268.4994925482015, 4033.4353190553347, 4186.392973018926, 4304.417349590252, 4563.977180114078, 4597.42532183477, 4855.805767769285, 5559.555235581144, 5575.414581468968, 5578.525825304854, 5593.272874526327, 3212.3254116635535, 3671.4125739881756, 3786.6213378662187, 3825.729001703531, 3866.3620502617036, 4589.908201836011, 5696.743646535916, 5761.395053519362, 5773.855653608652, 5791.623544469639, 3870.7226615217473, 3873.0247573737033, 3876.1860009398342, 3902.1025164211824, 3907.5115610548683, 3495.951214635599, 3667.0832291927018, 3840.9423858642144, 3902.6487616114687, 3935.9606403935595, 3878.437626544135, 4486.887827804266, 4519.666698324002, 4565.5206203348835, 4614.676972611885, 5560.86097847549, 5622.297212848394, 5623.887522249532, 5624.831255062355, 5650.660960342392, 1079.2930630437077, 1186.5728337798387, 1228.3121451986915, 1231.5073970411727, 1260.7415479826614, 749.7450866705346, 841.3101256969348, 862.2283056664251, 865.8916586768946, 868.5396739727954, 4121.5878412159145, 4355.234426139361, 4803.687760295569, 4868.488808675109, 4877.853664390074, 4359.934600980988, 4741.510706878917, 4990.525900039491, 5402.432744561843, 5411.751774856797, 4173.749575025508, 4355.324924636687, 4522.886927826765, 4683.367745354401, 4759.309900064472, 2444.853308801442, 2450.5786643746583, 2504.4490212153296, 2771.9050974864817, 2849.805649649758, 4505.152887316184, 4533.795979180944, 4596.621013695878, 5136.563392111638, 5302.09864323066, 5030.974845125824, 5345.625806193607, 5439.57938717267, 5528.927765462389, 5549.611527193058, 5232.319798426181, 5369.382521010134, 5430.15832545956, 5593.2101727446825, 5598.748056337475, 4356.324769660708, 4618.722876627413, 4662.93005604923, 4783.0, 4918.163912135017, 4583.166733306704, 5031.0, 5306.708131052829, 5363.410024897336, 5391.460853914734, 4949.30709700635, 5003.399592048978, 5509.421510741475, 5549.751305956128, 5630.6621602702335, 3326.0896715374047, 3434.715913917217, 3632.4914511968873, 3653.1232504199334, 3676.761010534307, 4706.058788242297, 4719.952800472089, 5370.36203957781, 5425.159100339453, 5429.5656347492895, 4986.379426686414, 5020.849374518771, 5137.8715532112, 5261.500157485186, 5276.577873770012, 4494.303382975644, 4589.439590539233, 4821.252705830602, 4861.857463496005, 5212.113940630026, 4818.518148185293, 5147.845564633067, 5224.9738751306, 5302.39022512416, 5369.778133120253, 5388.434214407587, 5453.61824672285, 5506.449355064461, 5516.786307012451, 5536.7231638417115, 5011.34172054395, 5036.748162591783, 5393.40672526014, 5417.295751552273, 5440.809571664972], "value": 3934.4912687039478, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6065403374114, 718.9424846012323, 755.9244075592442, 800.1558355934488, 827.300930713547, 14944.80441564675, 15133.167675777828, 15848.84151158488, 15875.444359447421, 15888.523344299661, 12910.612681619543, 13620.591382258523, 13627.909767218598, 13969.441222351094, 14514.274286285696, 850.9021462531811, 855.9743207703779, 870.5734190246765, 873.1922971251598, 873.6199753107419, 850.0819115355432, 871.4901782457271, 872.145297608341, 873.7771868173606, 875.7547886591765, 921.272194965976, 921.7234829551142, 922.6078916460511, 924.3852837862847, 924.6763632728558, 886.5877367024333, 894.8881389826272, 917.2020342302206, 917.8944421391559, 920.7652048727599, 682.754208484945, 683.282553319018, 684.1209046375384, 684.8048306232699, 685.4242436353487, 12159.635210943687, 13558.93220533901, 13785.51750688731, 15322.23388830554, 15599.376024958989, 14021.298935053308, 14503.1298122113, 14583.0, 14641.267936603233, 14714.852851471454, 11771.58799442024, 11957.701057473545, 11959.581414650445, 11988.973601211876, 12169.269843809312, 3695.2655035273974, 3701.38204829707, 3723.925521489555, 3799.7530160539477, 3831.63599458051, 92982.53508732481, 93682.53158734231, 94669.52665236699, 96302.51848740781, 98974.0102598972, 8992.820143597222, 10713.78572428547, 10876.184286178568, 11273.928976747196, 11427.428628568705, 23616.64575031376, 23833.761662383153, 23851.0, 23894.64158037631, 23929.7607023931, 7680.270374314431, 7711.537307761552, 7799.5320280783335, 7861.921380786119, 7975.800604984922, 4147.4815648044, 4209.726367786084, 4303.655707543389, 4313.9137217255475, 4451.198784218807, 3553.8578456861437, 3651.7626354286886, 3653.6163702811355, 3669.8532058717888, 3747.5315585551807, 694.8262934266434, 699.877521433747, 701.5475018613068, 719.3813320544327, 720.2653293640562, 662.777969380263, 668.2482207516543, 700.5411455496658, 708.7767353283699, 731.9853602928018, 9696.0, 10246.897531024744, 10391.584336626602, 10431.791364172674, 10478.63324783629, 684.7028220320851, 692.3906961873519, 699.1016543741268, 706.1631966120163, 708.4828075504807, 4788.867179712093, 4908.803647854046, 5001.124803159505, 5014.774335154927, 5024.447310795809, 105035.44610092884, 105619.94380056101, 106110.0, 106916.0, 107615.0, 91556.54221728849, 92866.53566732258, 93452.0, 93539.66150846283, 93990.59014114794, 75213.99999999891, 79985.71896105428, 80492.5975370131, 82796.0, 82922.58538707388, 9432.528373581155, 9644.18024467928, 9749.07383798538, 9833.5083245836, 9847.458389788344, 51610.22584661308, 52021.21968170556, 52494.68763280949, 52692.0, 54772.72613636906, 54268.0, 69151.96272055822, 69177.0, 70433.0, 70825.29174708393, 11858.347790871585, 11864.288142711464, 11917.940410297893, 11974.401279936143, 12580.182288151242, 10560.735981600521, 12591.11862169676, 12704.36478176106, 12803.679908002374, 12888.291143987171, 11993.580224692443, 12014.399280036137, 12022.398880055787, 12193.695157620776, 12495.625131246077, 11800.114991375853, 11815.645530634094, 11970.640880773592, 12016.519339226334, 12481.25112493217, 11924.463399147233, 11962.76074478523, 11965.581204657796, 12349.753004939672, 12385.752284954431, 9772.40298939352, 9921.45432001218, 10115.443650599287, 10136.898631013595, 10463.68608941733, 8474.957625211835, 9363.625454981997, 9669.854952175583, 9855.655052073143, 9880.604775809175], "value": 7428.433537776345, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1450.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 98.0, 112.0, 171.0, 143.0, 110.0, 109.0, 107.0, 128.0, 109.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 184.0, 185.0, 185.0, 184.0, 178.0, 183.0, 184.0, 183.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 75.0, 110.0, 81.0, 69.0, 59.0, 59.0, 76.0, 58.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 87.0, 71.0, 68.0, 69.0, 83.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, 88.0, 90.0, 88.0, 91.0, 88.0, 90.0, 87.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 99.0, 99.0, 99.0, 97.0, 99.0, 99.0, 99.0], "value": 98.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 87.0, 89.0, 86.0, 87.0, 85.0, 84.0, 82.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 57.0, 58.0, 57.0, 59.0, 59.0, 60.0, 59.0, 59.0, 58.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 71.0, 69.0, 79.0, 74.0, 70.0, 72.0, 71.0, 72.0, 73.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 90.0, 87.0, 87.0, 86.0, 86.0, 88.0, 87.0, 89.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 145.0, 151.0, 158.0, 140.0, 140.0, 142.0, 179.0, 145.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 175.0, 174.0, 174.0, 174.0, 176.0, 175.0, 175.0, 176.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 66.0, 67.0, 66.0], "value": 67.1, "unit": "score"}]}]} 04:36:35 INFO - Return code: 0 04:36:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:35 ERROR - # TBPL SUCCESS # 04:36:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:36:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:36:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:36:35 INFO - 2017-01-31 04:36:35.902 screenresolution[1659:9691] starting screenresolution argv=screenresolution get 04:36:35 INFO - 2017-01-31 04:36:35.904 screenresolution[1659:9691] Display 0: 1600x1200x32@60 04:36:35 INFO - 2017-01-31 04:36:35.911 screenresolution[1660:9694] starting screenresolution argv=screenresolution list 04:36:35 INFO - Available Modes on Display 0 04:36:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:36:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:36:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:36:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:36:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:36:36 INFO - Intel Iris: 04:36:36 INFO - Chipset Model: Intel Iris 04:36:36 INFO - Type: GPU 04:36:36 INFO - Bus: Built-In 04:36:36 INFO - VRAM (Dynamic, Max): 1536 MB 04:36:36 INFO - Vendor: Intel (0x8086) 04:36:36 INFO - Device ID: 0x0a2e 04:36:36 INFO - Revision ID: 0x0009 04:36:36 INFO - Displays: 04:36:36 INFO - Display: 04:36:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:36:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:36:36 INFO - Display Serial Number: 1600x1200 60 04:36:36 INFO - Main Display: Yes 04:36:36 INFO - Mirror: Off 04:36:36 INFO - Online: Yes 04:36:36 INFO - Rotation: Supported 04:36:36 INFO - Return code: 0 04:36:36 INFO - Running post-action listener: _resource_record_post_action 04:36:36 INFO - [mozharness: 2017-01-31 12:36:36.156138Z] Finished run-tests step (success) 04:36:36 INFO - Running post-run listener: _resource_record_post_run 04:36:36 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37753856; Write bytes: 551389696; Read time: 235; Write time: 1629 04:36:36 INFO - TinderboxPrint: CPU usage
53.7% 04:36:36 INFO - TinderboxPrint: I/O read bytes / time
37,753,856 / 235 04:36:36 INFO - TinderboxPrint: I/O write bytes / time
551,389,696 / 1,629 04:36:36 INFO - TinderboxPrint: CPU idle
2,228.6 (46.3%) 04:36:36 INFO - TinderboxPrint: CPU system
957.9 (19.9%) 04:36:36 INFO - TinderboxPrint: CPU user
1,623.2 (33.7%) 04:36:36 INFO - TinderboxPrint: Swap in / out
289,873,920 / 0 04:36:36 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 247732736; Write bytes: 237123584; Read time: 17757; Write time: 502 04:36:36 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30534656; Write bytes: 294945280; Read time: 179; Write time: 1078 04:36:36 INFO - Running post-run listener: _upload_blobber_files 04:36:36 INFO - Blob upload gear active. 04:36:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:36 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 04:36:36 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'] 04:36:36 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 04:36:37 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:36:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:38 INFO - (blobuploader) - INFO - Done attempting. 04:36:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:36:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:36:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:39 INFO - (blobuploader) - INFO - Done attempting. 04:36:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:36:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:36:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:40 INFO - (blobuploader) - INFO - Done attempting. 04:36:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:42 INFO - (blobuploader) - INFO - Done attempting. 04:36:42 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:42 INFO - Return code: 0 04:36:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eef84253c36c1a003968e9ddb1f29ab8d735d917d62a37ee257350c90c4609786f19fb97708d519978b49e9e7dd6dcb0e6984fd33db6e99ddddcbd072eb5a905", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6c9f07b5aa70ed38640827f3236b4cdf7f87b97ed6da092a2e84e0271cf2092565d71e458f8c37983a6059d1ba5d5170145b107f2dd7a63e3f95eec45ac8bb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8b737499f571179e933dfcce647162296d48d79725ab9a6805d9952907799eced4736e6d16d23ffa1aebdf5151c036f09952bdabf80c9b6579ab4b206603c63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f03cd9279c7517e8fad3173f01267fc50f492502eb51782c1afe86c581f987c3588b6c271818af8fa3d5fe6a232be510c2cf0911b51dcdca12a9077be35390c"} 04:36:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:42 INFO - Contents: 04:36:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eef84253c36c1a003968e9ddb1f29ab8d735d917d62a37ee257350c90c4609786f19fb97708d519978b49e9e7dd6dcb0e6984fd33db6e99ddddcbd072eb5a905", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6c9f07b5aa70ed38640827f3236b4cdf7f87b97ed6da092a2e84e0271cf2092565d71e458f8c37983a6059d1ba5d5170145b107f2dd7a63e3f95eec45ac8bb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8b737499f571179e933dfcce647162296d48d79725ab9a6805d9952907799eced4736e6d16d23ffa1aebdf5151c036f09952bdabf80c9b6579ab4b206603c63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f03cd9279c7517e8fad3173f01267fc50f492502eb51782c1afe86c581f987c3588b6c271818af8fa3d5fe6a232be510c2cf0911b51dcdca12a9077be35390c"} 04:36:42 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:42 INFO - Copying logs to upload dir... 04:36:42 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.780275 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-31 04:36:42.666090) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 04:36:42.673325) ========= 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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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/eef84253c36c1a003968e9ddb1f29ab8d735d917d62a37ee257350c90c4609786f19fb97708d519978b49e9e7dd6dcb0e6984fd33db6e99ddddcbd072eb5a905", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6c9f07b5aa70ed38640827f3236b4cdf7f87b97ed6da092a2e84e0271cf2092565d71e458f8c37983a6059d1ba5d5170145b107f2dd7a63e3f95eec45ac8bb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8b737499f571179e933dfcce647162296d48d79725ab9a6805d9952907799eced4736e6d16d23ffa1aebdf5151c036f09952bdabf80c9b6579ab4b206603c63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f03cd9279c7517e8fad3173f01267fc50f492502eb51782c1afe86c581f987c3588b6c271818af8fa3d5fe6a232be510c2cf0911b51dcdca12a9077be35390c"} build_url:https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/MK2Y2I8JQJeKCp-n2WxT1Q/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/eef84253c36c1a003968e9ddb1f29ab8d735d917d62a37ee257350c90c4609786f19fb97708d519978b49e9e7dd6dcb0e6984fd33db6e99ddddcbd072eb5a905", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6c9f07b5aa70ed38640827f3236b4cdf7f87b97ed6da092a2e84e0271cf2092565d71e458f8c37983a6059d1ba5d5170145b107f2dd7a63e3f95eec45ac8bb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8b737499f571179e933dfcce647162296d48d79725ab9a6805d9952907799eced4736e6d16d23ffa1aebdf5151c036f09952bdabf80c9b6579ab4b206603c63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f03cd9279c7517e8fad3173f01267fc50f492502eb51782c1afe86c581f987c3588b6c271818af8fa3d5fe6a232be510c2cf0911b51dcdca12a9077be35390c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 04:36:42.714258) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:36:42.714608) ========= 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.kKSxfjkcjg/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.QlVBBgHnAU/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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.005009 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 04:36:42.768109) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 04:36:42.768594) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 04:36:42.769061) ========= ========= Total master_lag: 0.22 =========