builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0276 starttime: 1485883047.39 results: success (0) buildid: 20170131072109 builduid: ad483a45e6ff414aa4709ad83e215e2e revision: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.391938) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.392406) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.392755) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.420486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.420910) ========= 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.afzfu26INP/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.gjBPb3KDnO/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 09:17:27-- 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% 161M=0s 2017-01-31 09:17:27 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088559 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.533786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.534115) ========= 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.afzfu26INP/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.gjBPb3KDnO/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.025351 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:27.583092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 09:17:27.583465) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 --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 ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.afzfu26INP/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.gjBPb3KDnO/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 09:17:27,691 truncating revision to first 12 chars 2017-01-31 09:17:27,691 Setting DEBUG logging. 2017-01-31 09:17:27,691 attempt 1/10 2017-01-31 09:17:27,691 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ee975d32deb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 09:17:28,888 unpacking tar archive at: mozilla-central-ee975d32deb9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.459871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 09:17:29.066787) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:29.067145) ========= script_repo_revision: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:29.067619) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:29.067958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 09:17:29.087854) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-31 09:17:29.088271) ========= /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.afzfu26INP/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.gjBPb3KDnO/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:17:29 INFO - MultiFileLogger online at 20170131 09:17:29 in /builds/slave/test 09:17:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:17:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:29 INFO - {'append_to_log': False, 09:17:29 INFO - 'base_work_dir': '/builds/slave/test', 09:17:29 INFO - 'blob_upload_branch': 'Firefox', 09:17:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:29 INFO - 'branch': 'Firefox', 09:17:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:29 INFO - 'config_files': ('talos/mac_config.py',), 09:17:29 INFO - 'default_actions': ('clobber', 09:17:29 INFO - 'read-buildbot-config', 09:17:29 INFO - 'download-and-extract', 09:17:29 INFO - 'populate-webroot', 09:17:29 INFO - 'create-virtualenv', 09:17:29 INFO - 'install', 09:17:29 INFO - 'run-tests'), 09:17:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:29 INFO - 'download_minidump_stackwalk': True, 09:17:29 INFO - 'download_symbols': 'ondemand', 09:17:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:29 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:29 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:29 INFO - 'installer_path': 'installer.exe', 09:17:29 INFO - 'log_level': 'info', 09:17:29 INFO - 'log_name': 'talos', 09:17:29 INFO - 'log_to_console': True, 09:17:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:29 INFO - 'opt_config_files': (), 09:17:29 INFO - 'pip_index': False, 09:17:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:29 INFO - 'cmd': ('bash', 09:17:29 INFO - '-c', 09:17:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:29 INFO - 'enabled': True, 09:17:29 INFO - 'halt_on_failure': False, 09:17:29 INFO - 'name': 'check_screen_resolution'},), 09:17:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:29 INFO - 'cmd': ('bash', 09:17:29 INFO - '-c', 09:17:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:29 INFO - 'enabled': True, 09:17:29 INFO - 'halt_on_failure': False, 09:17:29 INFO - 'name': 'check_screen_resolution'},), 09:17:29 INFO - 'run_cmd_checks_enabled': True, 09:17:29 INFO - 'sps_profile': False, 09:17:29 INFO - 'sps_profile_interval': 0, 09:17:29 INFO - 'suite': 'dromaeojs', 09:17:29 INFO - 'system_bits': '32', 09:17:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:29 INFO - 'title': 't-yosemite-r7-0276', 09:17:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:29 INFO - 'use_talos_json': True, 09:17:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:29 INFO - 'work_dir': 'build'} 09:17:29 INFO - [mozharness: 2017-01-31 17:17:29.301854Z] Running clobber step. 09:17:29 INFO - Running pre-action listener: _resource_record_pre_action 09:17:29 INFO - Running main action method: clobber 09:17:29 INFO - rmtree: /builds/slave/test/build 09:17:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - [mozharness: 2017-01-31 17:17:31.625967Z] Finished clobber step (success) 09:17:31 INFO - [mozharness: 2017-01-31 17:17:31.626098Z] Running read-buildbot-config step. 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: read_buildbot_config 09:17:31 INFO - Using buildbot properties: 09:17:31 INFO - { 09:17:31 INFO - "project": "", 09:17:31 INFO - "product": "firefox", 09:17:31 INFO - "script_repo_revision": "production", 09:17:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:17:31 INFO - "repository": "", 09:17:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:17:31 INFO - "buildid": "20170131072109", 09:17:31 INFO - "pgo_build": "False", 09:17:31 INFO - "basedir": "/builds/slave/test", 09:17:31 INFO - "buildnumber": 47, 09:17:31 INFO - "slavename": "t-yosemite-r7-0276", 09:17:31 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:17:31 INFO - "platform": "yosemite_r7", 09:17:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:17:31 INFO - "branch": "mozilla-central", 09:17:31 INFO - "repo_path": "mozilla-central", 09:17:31 INFO - "revision": "ee975d32deb9eaa5641f45428cd6a4b5b555a8f5", 09:17:31 INFO - "stage_platform": "macosx64", 09:17:31 INFO - "builduid": "ad483a45e6ff414aa4709ad83e215e2e", 09:17:31 INFO - "slavebuilddir": "test" 09:17:31 INFO - } 09:17:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:17:31 INFO - Running post-action listener: _resource_record_post_action 09:17:31 INFO - [mozharness: 2017-01-31 17:17:31.629544Z] Finished read-buildbot-config step (success) 09:17:31 INFO - [mozharness: 2017-01-31 17:17:31.629632Z] Running download-and-extract step. 09:17:31 INFO - Running pre-action listener: _resource_record_pre_action 09:17:31 INFO - Running main action method: download_and_extract 09:17:31 INFO - mkdir: /builds/slave/test/build/tests 09:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:31 INFO - trying https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:17:31 INFO - Downloading https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/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 09:17:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/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 09:17:33 INFO - Downloaded 1199 bytes. 09:17:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:17:33 INFO - Using the following test package requirements: 09:17:33 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:17:33 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:33 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:17:33 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'jsshell-mac.zip'], 09:17:33 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:17:33 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:17:33 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:17:33 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:17:33 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:17:33 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:17:33 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:33 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:17:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:33 INFO - Fetch https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:17:34 INFO - Expected file size: 16371958 09:17:34 INFO - Obtained file size: 16371958 09:17:41 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 09:17:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:17:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:41 INFO - Fetch https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:17:42 INFO - Expected file size: 16371958 09:17:42 INFO - Obtained file size: 16371958 09:17:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:17:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:17:48 INFO - Fetch https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:17:50 INFO - Expected file size: 14403454 09:17:50 INFO - Obtained file size: 14403454 09:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:50 INFO - trying https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:17:50 INFO - Downloading https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:17:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/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 09:17:53 INFO - Downloaded 70215464 bytes. 09:17:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:17:53 INFO - mkdir: /builds/slave/test/properties 09:17:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:53 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:53 INFO - Contents: 09:17:53 INFO - build_url:https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:17:54 INFO - Running post-action listener: _resource_record_post_action 09:17:54 INFO - Running post-action listener: set_extra_try_arguments 09:17:54 INFO - [mozharness: 2017-01-31 17:17:54.402077Z] Finished download-and-extract step (success) 09:17:54 INFO - [mozharness: 2017-01-31 17:17:54.402229Z] Running populate-webroot step. 09:17:54 INFO - Running pre-action listener: _resource_record_pre_action 09:17:54 INFO - Running main action method: populate_webroot 09:17:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:54 INFO - '--tppagecycles', 09:17:54 INFO - '7'], 09:17:54 INFO - 'tests': ['tsvgm']}}, 09:17:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['damp', 'tps']}, 09:17:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'tests': ['damp', 'tps']}, 09:17:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['dromaeo_dom']}, 09:17:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['basic_compositor_video']}, 09:17:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['a11yr', 09:17:54 INFO - 'ts_paint', 09:17:54 INFO - 'tpaint', 09:17:54 INFO - 'sessionrestore', 09:17:54 INFO - 'sessionrestore_no_auto_restore', 09:17:54 INFO - 'tabpaint']}, 09:17:54 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:54 INFO - 'ts_paint', 09:17:54 INFO - 'tpaint', 09:17:54 INFO - 'sessionrestore', 09:17:54 INFO - 'sessionrestore_no_auto_restore', 09:17:54 INFO - 'tabpaint']}, 09:17:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:54 INFO - 'ts_paint', 09:17:54 INFO - 'tpaint', 09:17:54 INFO - 'sessionrestore', 09:17:54 INFO - 'sessionrestore_no_auto_restore', 09:17:54 INFO - 'tabpaint']}, 09:17:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:54 INFO - 'ts_paint', 09:17:54 INFO - 'tpaint', 09:17:54 INFO - 'sessionrestore', 09:17:54 INFO - 'sessionrestore_no_auto_restore', 09:17:54 INFO - 'tabpaint']}, 09:17:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['a11yr', 09:17:54 INFO - 'ts_paint', 09:17:54 INFO - 'tpaint', 09:17:54 INFO - 'sessionrestore', 09:17:54 INFO - 'sessionrestore_no_auto_restore', 09:17:54 INFO - 'tabpaint']}, 09:17:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['a11yr', 09:17:54 INFO - 'ts_paint', 09:17:54 INFO - 'tpaint', 09:17:54 INFO - 'sessionrestore', 09:17:54 INFO - 'sessionrestore_no_auto_restore', 09:17:54 INFO - 'tabpaint']}, 09:17:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['tsvgx', 09:17:54 INFO - 'tsvgr_opacity', 09:17:54 INFO - 'tart', 09:17:54 INFO - 'tscrollx', 09:17:54 INFO - 'cart', 09:17:54 INFO - 'tsvg_static']}, 09:17:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:54 INFO - 'tsvgr_opacity', 09:17:54 INFO - 'tart', 09:17:54 INFO - 'tscrollx', 09:17:54 INFO - 'cart', 09:17:54 INFO - 'tsvg_static']}, 09:17:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'talos_options': ['--disable-e10s'], 09:17:54 INFO - 'tests': ['tp5o']}, 09:17:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:17:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'talos_options': ['--disable-e10s', 09:17:54 INFO - '--xperf_path', 09:17:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:54 INFO - 'tests': ['tp5n']}, 09:17:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:17:54 INFO - 'talos_options': ['--xperf_path', 09:17:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:54 INFO - 'tests': ['tp5n']}}, 09:17:54 INFO - 'talos.zip': {'path': '', 09:17:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:54 INFO - Running post-action listener: _resource_record_post_action 09:17:54 INFO - [mozharness: 2017-01-31 17:17:54.420445Z] Finished populate-webroot step (success) 09:17:54 INFO - [mozharness: 2017-01-31 17:17:54.420585Z] Running create-virtualenv step. 09:17:54 INFO - Running pre-action listener: _resource_record_pre_action 09:17:54 INFO - Running main action method: create_virtualenv 09:17:54 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:17:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:54 INFO - Using real prefix '/tools/python27' 09:17:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:55 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:58 INFO - Installing pip.................done. 09:17:58 INFO - Return code: 0 09:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:17:58 INFO - Reading from file tmpfile_stdout 09:17:58 INFO - Output received: 09:17:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:17:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:58 INFO - 'HOME': '/Users/cltbld', 09:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:58 INFO - 'LOGNAME': 'cltbld', 09:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:58 INFO - 'MOZ_NO_REMOTE': '1', 09:17:58 INFO - 'NO_EM_RESTART': '1', 09:17:58 INFO - 'PAGER': '/bin/cat', 09:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:58 INFO - 'PWD': '/builds/slave/test', 09:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:58 INFO - 'SHELL': '/bin/bash', 09:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:17:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:58 INFO - 'USER': 'cltbld', 09:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:58 INFO - 'XPC_FLAGS': '0x0', 09:17:58 INFO - 'XPC_SERVICE_NAME': '0', 09:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:17:58 INFO - Cleaning up... 09:17:58 INFO - Return code: 0 09:17:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:58 INFO - 'HOME': '/Users/cltbld', 09:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:58 INFO - 'LOGNAME': 'cltbld', 09:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:58 INFO - 'MOZ_NO_REMOTE': '1', 09:17:58 INFO - 'NO_EM_RESTART': '1', 09:17:58 INFO - 'PAGER': '/bin/cat', 09:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:58 INFO - 'PWD': '/builds/slave/test', 09:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:58 INFO - 'SHELL': '/bin/bash', 09:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:17:58 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:58 INFO - 'USER': 'cltbld', 09:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:58 INFO - 'XPC_FLAGS': '0x0', 09:17:58 INFO - 'XPC_SERVICE_NAME': '0', 09:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:58 INFO - Downloading/unpacking psutil>=3.1.1 09:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:02 INFO - Installing collected packages: psutil 09:18:02 INFO - Running setup.py install for psutil 09:18:02 INFO - building 'psutil._psutil_osx' extension 09:18:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:18:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:18:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:18:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:18:03 INFO - building 'psutil._psutil_posix' extension 09:18:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:18:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:18:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:18:03 INFO - ^ 09:18:03 INFO - 1 warning generated. 09:18:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:18:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:03 INFO - Successfully installed psutil 09:18:03 INFO - Cleaning up... 09:18:03 INFO - Return code: 0 09:18:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:03 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:03 INFO - 'HOME': '/Users/cltbld', 09:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:03 INFO - 'LOGNAME': 'cltbld', 09:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:03 INFO - 'MOZ_NO_REMOTE': '1', 09:18:03 INFO - 'NO_EM_RESTART': '1', 09:18:03 INFO - 'PAGER': '/bin/cat', 09:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:03 INFO - 'PWD': '/builds/slave/test', 09:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:03 INFO - 'SHELL': '/bin/bash', 09:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:03 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:03 INFO - 'USER': 'cltbld', 09:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:03 INFO - 'XPC_FLAGS': '0x0', 09:18:03 INFO - 'XPC_SERVICE_NAME': '0', 09:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:18:06 INFO - Installing collected packages: mozsystemmonitor 09:18:06 INFO - Running setup.py install for mozsystemmonitor 09:18:06 INFO - Successfully installed mozsystemmonitor 09:18:06 INFO - Cleaning up... 09:18:06 INFO - Return code: 0 09:18:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:06 INFO - 'HOME': '/Users/cltbld', 09:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:06 INFO - 'LOGNAME': 'cltbld', 09:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:06 INFO - 'MOZ_NO_REMOTE': '1', 09:18:06 INFO - 'NO_EM_RESTART': '1', 09:18:06 INFO - 'PAGER': '/bin/cat', 09:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:06 INFO - 'PWD': '/builds/slave/test', 09:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:06 INFO - 'SHELL': '/bin/bash', 09:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:06 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:06 INFO - 'USER': 'cltbld', 09:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:06 INFO - 'XPC_FLAGS': '0x0', 09:18:06 INFO - 'XPC_SERVICE_NAME': '0', 09:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:07 INFO - Downloading/unpacking jsonschema==2.5.1 09:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:10 INFO - warning: no files found matching '*.txt' 09:18:10 INFO - no previously-included directories found matching 'build' 09:18:10 INFO - no previously-included directories found matching 'dist' 09:18:10 INFO - no previously-included directories found matching '.git*' 09:18:10 INFO - Installing collected packages: jsonschema, functools32 09:18:10 INFO - Running setup.py install for functools32 09:18:10 INFO - warning: no files found matching '*.txt' 09:18:10 INFO - no previously-included directories found matching 'build' 09:18:10 INFO - no previously-included directories found matching 'dist' 09:18:10 INFO - no previously-included directories found matching '.git*' 09:18:10 INFO - Successfully installed jsonschema functools32 09:18:10 INFO - Cleaning up... 09:18:10 INFO - Return code: 0 09:18:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:10 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:10 INFO - 'HOME': '/Users/cltbld', 09:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:10 INFO - 'LOGNAME': 'cltbld', 09:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:10 INFO - 'MOZ_NO_REMOTE': '1', 09:18:10 INFO - 'NO_EM_RESTART': '1', 09:18:10 INFO - 'PAGER': '/bin/cat', 09:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:10 INFO - 'PWD': '/builds/slave/test', 09:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:10 INFO - 'SHELL': '/bin/bash', 09:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:10 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:10 INFO - 'USER': 'cltbld', 09:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:10 INFO - 'XPC_FLAGS': '0x0', 09:18:10 INFO - 'XPC_SERVICE_NAME': '0', 09:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:18:10 INFO - Cleaning up... 09:18:10 INFO - Return code: 0 09:18:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:10 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:10 INFO - 'HOME': '/Users/cltbld', 09:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:10 INFO - 'LOGNAME': 'cltbld', 09:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:10 INFO - 'MOZ_NO_REMOTE': '1', 09:18:10 INFO - 'NO_EM_RESTART': '1', 09:18:10 INFO - 'PAGER': '/bin/cat', 09:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:10 INFO - 'PWD': '/builds/slave/test', 09:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:10 INFO - 'SHELL': '/bin/bash', 09:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:10 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:10 INFO - 'USER': 'cltbld', 09:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:10 INFO - 'XPC_FLAGS': '0x0', 09:18:10 INFO - 'XPC_SERVICE_NAME': '0', 09:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:11 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:13 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:14 INFO - Downloading docopt-0.6.1.tar.gz 09:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:15 INFO - Running setup.py install for blobuploader 09:18:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:15 INFO - Running setup.py install for requests 09:18:15 INFO - Running setup.py install for docopt 09:18:15 INFO - Successfully installed blobuploader requests docopt 09:18:15 INFO - Cleaning up... 09:18:15 INFO - Return code: 0 09:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:15 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:15 INFO - 'HOME': '/Users/cltbld', 09:18:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:15 INFO - 'LOGNAME': 'cltbld', 09:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:15 INFO - 'MOZ_NO_REMOTE': '1', 09:18:15 INFO - 'NO_EM_RESTART': '1', 09:18:15 INFO - 'PAGER': '/bin/cat', 09:18:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:15 INFO - 'PWD': '/builds/slave/test', 09:18:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:15 INFO - 'SHELL': '/bin/bash', 09:18:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:15 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:15 INFO - 'USER': 'cltbld', 09:18:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:15 INFO - 'XPC_FLAGS': '0x0', 09:18:15 INFO - 'XPC_SERVICE_NAME': '0', 09:18:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-kk_p5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ZwpiS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-pUWmwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-N09x8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-LWyZoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ChOhna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-w9FOrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:16 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-0LkGtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-lDUpFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-e4BRvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-bgw_yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UoHcFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-WJ_77D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-sCvZfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-i8lDmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Kr2hAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:17 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uOf9Qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:17 INFO - Running setup.py install for manifestparser 09:18:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:18 INFO - Running setup.py install for mozcrash 09:18:18 INFO - Running setup.py install for mozdebug 09:18:18 INFO - Running setup.py install for mozdevice 09:18:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:18 INFO - Running setup.py install for mozfile 09:18:18 INFO - Running setup.py install for mozhttpd 09:18:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:18 INFO - Running setup.py install for mozinfo 09:18:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Running setup.py install for mozInstall 09:18:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Running setup.py install for mozleak 09:18:19 INFO - Running setup.py install for mozlog 09:18:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Running setup.py install for moznetwork 09:18:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Running setup.py install for mozprocess 09:18:19 INFO - Running setup.py install for mozprofile 09:18:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:19 INFO - Running setup.py install for mozrunner 09:18:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:20 INFO - Running setup.py install for mozscreenshot 09:18:20 INFO - Running setup.py install for moztest 09:18:20 INFO - Running setup.py install for mozversion 09:18:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:20 INFO - Cleaning up... 09:18:20 INFO - Return code: 0 09:18:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:21 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:21 INFO - 'HOME': '/Users/cltbld', 09:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:21 INFO - 'LOGNAME': 'cltbld', 09:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:21 INFO - 'MOZ_NO_REMOTE': '1', 09:18:21 INFO - 'NO_EM_RESTART': '1', 09:18:21 INFO - 'PAGER': '/bin/cat', 09:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:21 INFO - 'PWD': '/builds/slave/test', 09:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:21 INFO - 'SHELL': '/bin/bash', 09:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:21 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:21 INFO - 'USER': 'cltbld', 09:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:21 INFO - 'XPC_FLAGS': '0x0', 09:18:21 INFO - 'XPC_SERVICE_NAME': '0', 09:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:21 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uqn9Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rPSKLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-PHQXjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-i013Z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-gLdxDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ypMFNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Bvw_sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-cpb3eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-B2MJpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-g2wdXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-gnu4Te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-cs3Rfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-s6wFEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6t2lfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-YGL6SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-lrkhJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-QkvoBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:26 INFO - Downloading blessings-1.6.tar.gz 09:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:26 INFO - Installing collected packages: blessings 09:18:26 INFO - Running setup.py install for blessings 09:18:27 INFO - Successfully installed blessings 09:18:27 INFO - Cleaning up... 09:18:27 INFO - Return code: 0 09:18:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:27 INFO - Reading from file tmpfile_stdout 09:18:27 INFO - Current package versions: 09:18:27 INFO - blessings == 1.6 09:18:27 INFO - blobuploader == 1.2.4 09:18:27 INFO - docopt == 0.6.1 09:18:27 INFO - functools32 == 3.2.3-2 09:18:27 INFO - jsonschema == 2.5.1 09:18:27 INFO - manifestparser == 1.1 09:18:27 INFO - mozInstall == 1.12 09:18:27 INFO - mozcrash == 1.0 09:18:27 INFO - mozdebug == 0.1 09:18:27 INFO - mozdevice == 0.49 09:18:27 INFO - mozfile == 1.2 09:18:27 INFO - mozhttpd == 0.7 09:18:27 INFO - mozinfo == 0.9 09:18:27 INFO - mozleak == 0.1 09:18:27 INFO - mozlog == 3.4 09:18:27 INFO - moznetwork == 0.27 09:18:27 INFO - mozprocess == 0.24 09:18:27 INFO - mozprofile == 0.28 09:18:27 INFO - mozrunner == 6.13 09:18:27 INFO - mozscreenshot == 0.1 09:18:27 INFO - mozsystemmonitor == 0.3 09:18:27 INFO - moztest == 0.8 09:18:27 INFO - mozversion == 1.4 09:18:27 INFO - psutil == 3.1.1 09:18:27 INFO - requests == 1.2.3 09:18:27 INFO - wsgiref == 0.1.2 09:18:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:27 INFO - 'HOME': '/Users/cltbld', 09:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:27 INFO - 'LOGNAME': 'cltbld', 09:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:27 INFO - 'MOZ_NO_REMOTE': '1', 09:18:27 INFO - 'NO_EM_RESTART': '1', 09:18:27 INFO - 'PAGER': '/bin/cat', 09:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:27 INFO - 'PWD': '/builds/slave/test', 09:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:27 INFO - 'SHELL': '/bin/bash', 09:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:27 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:27 INFO - 'USER': 'cltbld', 09:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:27 INFO - 'XPC_FLAGS': '0x0', 09:18:27 INFO - 'XPC_SERVICE_NAME': '0', 09:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:18:27 INFO - Cleaning up... 09:18:27 INFO - Return code: 0 09:18:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 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 0x1073c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b2c546f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, '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.afzfu26INP/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.gjBPb3KDnO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:27 INFO - 'HOME': '/Users/cltbld', 09:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:27 INFO - 'LOGNAME': 'cltbld', 09:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:27 INFO - 'MOZ_NO_REMOTE': '1', 09:18:27 INFO - 'NO_EM_RESTART': '1', 09:18:27 INFO - 'PAGER': '/bin/cat', 09:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:27 INFO - 'PWD': '/builds/slave/test', 09:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:27 INFO - 'SHELL': '/bin/bash', 09:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:27 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:27 INFO - 'USER': 'cltbld', 09:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:27 INFO - 'XPC_FLAGS': '0x0', 09:18:27 INFO - 'XPC_SERVICE_NAME': '0', 09:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:18:27 INFO - Cleaning up... 09:18:27 INFO - Return code: 0 09:18:27 INFO - Running post-action listener: _resource_record_post_action 09:18:27 INFO - Running post-action listener: _start_resource_monitoring 09:18:28 INFO - Starting resource monitoring. 09:18:28 INFO - [mozharness: 2017-01-31 17:18:28.030534Z] Finished create-virtualenv step (success) 09:18:28 INFO - [mozharness: 2017-01-31 17:18:28.031476Z] Running install step. 09:18:28 INFO - Running pre-action listener: _resource_record_pre_action 09:18:28 INFO - Running main action method: install 09:18:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:28 INFO - Reading from file tmpfile_stdout 09:18:28 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:28 INFO - Reading from file tmpfile_stdout 09:18:28 INFO - Output received: 09:18:28 INFO - Usage: mozinstall [options] installer 09:18:28 INFO - Options: 09:18:28 INFO - -h, --help show this help message and exit 09:18:28 INFO - -d DEST, --destination=DEST 09:18:28 INFO - Directory to install application into. [default: 09:18:28 INFO - "/builds/slave/test"] 09:18:28 INFO - --app=APP Application being installed. [default: firefox] 09:18:28 INFO - mkdir: /builds/slave/test/build/application 09:18:28 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'] 09:18:28 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 09:18:50 INFO - Reading from file tmpfile_stdout 09:18:50 INFO - Output received: 09:18:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:50 INFO - Running post-action listener: _resource_record_post_action 09:18:50 INFO - [mozharness: 2017-01-31 17:18:50.414175Z] Finished install step (success) 09:18:50 INFO - [mozharness: 2017-01-31 17:18:50.414316Z] Running run-tests step. 09:18:50 INFO - Running pre-action listener: _resource_record_pre_action 09:18:50 INFO - Running main action method: run_tests 09:18:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:50 INFO - 2017-01-31 09:18:50.429 screenresolution[1632:7424] starting screenresolution argv=screenresolution get 09:18:50 INFO - 2017-01-31 09:18:50.431 screenresolution[1632:7424] Display 0: 1600x1200x32@60 09:18:50 INFO - 2017-01-31 09:18:50.437 screenresolution[1633:7426] starting screenresolution argv=screenresolution list 09:18:50 INFO - Available Modes on Display 0 09:18:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:50 INFO - Intel Iris: 09:18:50 INFO - Chipset Model: Intel Iris 09:18:50 INFO - Type: GPU 09:18:50 INFO - Bus: Built-In 09:18:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:50 INFO - Vendor: Intel (0x8086) 09:18:50 INFO - Device ID: 0x0a2e 09:18:50 INFO - Revision ID: 0x0009 09:18:50 INFO - Displays: 09:18:50 INFO - Display: 09:18:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:50 INFO - Display Serial Number: 1600x1200 60 09:18:50 INFO - Main Display: Yes 09:18:50 INFO - Mirror: Off 09:18:50 INFO - Online: Yes 09:18:50 INFO - Rotation: Supported 09:18:50 INFO - Return code: 0 09:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:50 INFO - Python 2.7.3 09:18:50 INFO - Return code: 0 09:18:50 INFO - grabbing minidump binary from tooltool 09:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:50 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 0x10719fd30>, '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 0x1070c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107194be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:18:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:18:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:18:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:50 INFO - Return code: 0 09:18:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:18:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afzfu26INP/Render', 09:18:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:50 INFO - 'HOME': '/Users/cltbld', 09:18:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:50 INFO - 'LOGNAME': 'cltbld', 09:18:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:50 INFO - 'MOZ_NO_REMOTE': '1', 09:18:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:50 INFO - 'NO_EM_RESTART': '1', 09:18:50 INFO - 'PAGER': '/bin/cat', 09:18:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:50 INFO - 'PWD': '/builds/slave/test', 09:18:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:50 INFO - 'SHELL': '/bin/bash', 09:18:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gjBPb3KDnO/Listeners', 09:18:50 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 09:18:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:50 INFO - 'USER': 'cltbld', 09:18:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:50 INFO - 'XPC_FLAGS': '0x0', 09:18:50 INFO - 'XPC_SERVICE_NAME': '0', 09:18:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:18:51 INFO - mozversion application_buildid: 20170131072109 09:18:51 INFO - mozversion application_changeset: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 09:18:51 INFO - mozversion application_display_name: Nightly 09:18:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:51 INFO - mozversion application_name: Firefox 09:18:51 INFO - mozversion application_remotingname: firefox 09:18:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:18:51 INFO - mozversion application_vendor: Mozilla 09:18:51 INFO - mozversion application_version: 54.0a1 09:18:51 INFO - mozversion platform_buildid: 20170131072109 09:18:51 INFO - mozversion platform_changeset: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 09:18:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:18:51 INFO - mozversion platform_version: 54.0a1 09:18:51 INFO - using testdate: 1485883131 09:18:51 INFO - actual date: 1485883131 09:18:51 INFO - starting webserver on 'localhost:49243' 09:18:51 INFO - SUITE-START | Running 2 tests 09:18:51 INFO - TEST-START | dromaeo_css 09:18:51 INFO - operating with platform_type : mac_ 09:18:51 INFO - Initialising browser for dromaeo_css test... 09:18:51 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpF45Mux/profile http://localhost:49243/getInfo.html) 09:18:53 INFO - PROCESS | 1643 | 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] 09:18:53 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 09:18:53 INFO - PROCESS | 1643 | colorDepth:24 09:18:53 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 09:18:53 INFO - PROCESS | 1643 | __metrics 09:18:53 INFO - PROCESS | 1643 | 1485883133636 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:18:53 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:18:53 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:18:54 INFO - TEST-INFO | 1643: exit 0 09:18:54 INFO - Browser initialized. 09:18:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:54 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpF45Mux/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:06 INFO - PROCESS | 1645 | RSS: Main: 237563904 09:19:06 INFO - PROCESS | 1645 | 09:22:10 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 09:22:12 INFO - PROCESS | 1645 | RSS: Main: 477110272 09:22:12 INFO - PROCESS | 1645 | 09:23:56 INFO - PROCESS | 1645 | ************************* 09:23:56 INFO - PROCESS | 1645 | A coding exception was thrown and uncaught in a Task. 09:23:56 INFO - PROCESS | 1645 | 09:23:56 ERROR - PROCESS | 1645 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:23:56 INFO - PROCESS | 1645 | Full stack: 09:23:56 INFO - PROCESS | 1645 | ************************* 09:25:15 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 09:25:16 INFO - PROCESS | 1645 | RSS: Main: 375934976 09:25:16 INFO - PROCESS | 1645 | 09:28:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 09:28:19 INFO - PROCESS | 1645 | RSS: Main: 342691840 09:28:19 INFO - PROCESS | 1645 | 09:31:21 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 09:31:22 INFO - PROCESS | 1645 | RSS: Main: 339156992 09:31:22 INFO - PROCESS | 1645 | 09:34:25 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 09:34:26 INFO - PROCESS | 1645 | RSS: Main: 334233600 09:34:26 INFO - PROCESS | 1645 | 09:37:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 09:37:30 INFO - PROCESS | 1645 | RSS: Main: 491937792 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | __start_tp_report 09:37:30 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 09:37:30 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 09:37:30 INFO - PROCESS | 1645 | |i|pagename|runs| 09:37:30 INFO - PROCESS | 1645 | |0;dojo.html;3292.0946739646597;3311.056348940552;3316.4693649016162;3329.334133173366;3366.4108780963325;5600.1599760036015;5657.462541058603;5731.713414329282;5775.653460792355;5805.564582656302;5326.946730532693;5677.063284558053;5704.971475142618;5718.885622287552;5727.083666613343;4371.497277813044;5143.331366922304;5155.896882062375;5160.612954028444;5201.40183878855;5238.895222095556;5240.711760853152;5243.187305967562;5259.6055295852775;5267.23625074364;5222.451642577532;5237.502437268454;5237.7381130943495;5238.633295669305;5250.474952504738;5237.528622423981;5247.580193584504;5247.842564723064;5250;5252.947470525303;4350.934735978963;5097.209932460474;5212.556932660728;5218.13900706383;5226.477352264781;4691.554302341272;5675.914861277084;5729.369769325371;5737.655740655574;5745.741441635137;6086.939130608682;6091.360407157229;6093.634381937098;6104.755809767604;6112.789476565617;6162.7226774795245;6163.445289923883;6221.595596286227;6226.470749986257;6265.154204182468;5817.941820581825;5821.650700957956;5828.0966450200285;5831.591788574844;5858.297004359504;6331.620102793847;6365.2998170201245;6367.872642547123;6380.521460890452;6396.328385519453;6065.090236464565;6130.080487926846;6131.846703832351;6133.754649813957;6134.478569321657;6171.6297022178815;6213.96893015532;6215.595986260879;6229.221347331583;6293.056041593706;6146.938530614726;6173.969130154321;6221.688915554295;6222.564420490613;6259.3427690091885;6311.684415779193;6320.968395158086;6322.304546499881;6329.841753956096;6346.428821406095;4857.247126695368;4909.2881532177635;4912.8771780705765;4956.529129732669;4987.351644286219;4389.056352884081;4434.357018232338;4438.201123797683;4438.467383913951;4449.221386257392;5768.596198266165;5901.734421951022;5908.763649454059;5911.76352945886;5945.7621695132575;6298.433141017239;6348.365163483615;6358.682065896779;6374.298827129012;6385.233771947396;5678.659280443187;5727.914081288784;5744.655320680772;5882.23530940975;5892.469677729025;4508.797104130322;5402.35171779389;5440.91838622421;5505.201745746845;5539.19681646159;5617.045102332531;6057.969710151421;6314.210723659543;6321.209848768812;6346.841328966628;6228.968855155696;6323.462505687068;6342.048692696132;6348.5238607105575;6360.014197799349;6238.064290356301;6256.0303153011355;6258.342873998165;6288.874222515433;6335.366463353628;5188.584913206859;6231.096491008688;6240.812775616739;6250.843728906813;6264;5685.891251206141;6067.908981365372;6253.6560489173535;6278.7488500461295;6286.622802631857;5602.607817452902;5992.340842507408;6034.849128771816;6053.031514957585;6061.72722227492;6084.482818960261;6087.391260873877;6101.237345331833;6111.786087486917;6114.0828875669;5920.73356698941;5931.851703707269;5935.376785437467;5941.792037278675;5957.523398128226;6046.183765191643;6070.2108725865355;6072.696365181635;6081.148639190428;6112.144299798121;5903.350631430625;5937.614055086406;5960.940390596039;6021.307549631849;6032.034874420072 09:37:30 INFO - PROCESS | 1645 | |1;ext.html;434.57194663256695;490.5486952004156;493.15424047758086;493.7062447843532;516.2076213013025;30482.69517304829;38910.61089389109;41269.96825079375;41658.791706041506;43371.56628433715;42193;42468.93827654303;42553;42680;42807.571924280746;8338.66645334189;9137.131972462605;9326.067393260653;9348;9349;8372.120927302636;10890.346579205234;11215.485909402209;11217.775644487105;11362.863713628612;11089.33463992159;11136.777264454706;11154.721131971724;11168;11173.944130279378;8311.16888311164;8555.657773689045;11094.667159985172;11150.553977840877;11155.665330040112;10703.625373111981;11016.449177541172;11034.834477482844;11198.440077996067;11236.49435775384;40369.79815100935;40759.592404076175;40790.18419631591;40927.590724092974;41027.97430064272;3716.21959388526;3986.521617405901;3992.0818211811447;4019.799010049486;4028.6978476614076;30368.54447183294;30858.537121943187;31023.37953240945;31059.22351941197;31134.910278140156;3149.3543823516125;3213.2770126721534;3255.9837200813845;3316.7844090134304;3329.9500507492407;375039.99999999726;375323;375565;376870;492387;62250.066249006304;62521;62701;62701.68649156725;62791;60051.09923351153;61882;61945;62132.378676213564;62219.6889015552;16056.598585035468;16293.429729959631;16293.59266018359;16320.265588048565;16321.673566528603;11121.888781112248;11424;11428.2000259981;12533;12610.117291789487;13491.460341586422;15987.840121598867;16193.919030404773;17848.55378615545;18319.633607327778;5265.842024739264;6507.665928484744;6526.314736952551;6558.737650493927;6572.737090516422;830.8130670599066;853.2022558907441;859.8065435277013;863.667488017118;870.8084221471266;29514.704852951625;29892.402151956838;29923;30790;30906.0728178155;1021.596469394585;1030.1655658916302;1048.3762161513887;1053.8419237114495;1190.9579118271513;15013.024153430211;15486.380544778083;15529.223538823013;15715.921420392824;15901.853249811358;601448;606808;607281;608116;608724;60942.69528652328;61135.694321528106;61171;61296;61392.69303653453;1251.4243448014015;1371.5131128449457;1394.3864699532173;1418.936147873348;1435.2823588205897;28425.715742842305;29341.85329073341;29364.7063529362;29966.25084372908;30198.943036993598;52270.73864630653;52621.684457888856;52647;52776.47223527869;52790;535993;536274;536596;538233;539686;39912.00219994524;40426.59573404228;40728.59271407248;40890.59109408987;41217.793911030254;45899.541004589526;46444.83887902829;46812.063758725315;47410.28884566803;47653.28520072271;37232.44151337786;37939;38327.616723832405;39133.41299880447;39531;37696.43455348117;38348.44267135085;39129.804350979204;39174;39376.803115985385;35826.820865896545;37100.81449592735;37373;37485.43771843479;37968.24063518769;31591;33103.70230261586;33328.66671333255;33360.33279334448;33857.3978074283;19917.402477925687;24509.38726531851;24716.629250560898;24878.751212487645;24983.376745442194 09:37:30 INFO - PROCESS | 1645 | |2;jquery.html;370.84053856841547;390.84756944791536;396.8075483390553;401.2984943838126;424.39311784148674;14844.628884277898;15991.360345586178;18966;20841.583168336663;21496.032678529464;20797.584048319026;21027.36917892462;21563.13747450104;22218.555628887414;22301.665475017846;5614.129809879455;5673.773049078032;5747.683877386742;5824.941750582482;5920.615160014585;4142.71001029928;4367.694261401703;5684.772609095631;5802.564807639423;5806.59353845231;6154.415330543591;6425.742970281207;6445.033245013238;6516.641584712839;6534.281229064799;6294.999999999977;6442.0014897690935;6495.642739649317;6517.348265173492;6543.476521878239;5697.401772813878;5714.371419143891;5718.714064296769;5724.227229324029;5734.770609175628;23276.883615581814;23962.880185599133;24365.63451548228;24935.87532062328;25377.873110634515;2497.725250222474;2543.974560254411;2553.7190908999996;2577.561814491541;2587.0815860369485;8570.914290857074;10131.848022279672;10140.594376224943;10265.692029239135;10569.63006294776;1817.6637322095455;1868.5702288473726;1879.6146789908037;1897.4781934967857;1898.3545594497798;140535;143138;144051;145443;147420;11649.941750291364;11736.417988042616;11831.290122592673;11888.465019074163;11989.64031079069;15678.294476748573;15718.764218536731;15733.842661573237;15735.68528629444;15736.134512601917;8342.499450033018;8685.522296273639;8724.171203735636;8747.65009399617;8768.912310876809;8366.623501942428;8385.203405676451;8403;8553.871691924625;8560.743177704679;4657.86026419208;4750.714957102516;4753.263244197155;4820.6143508519235;4841.709497430095;1966.2724791827115;2010.547626783962;2028.198861449719;2029.1781828359537;2032.2074390987527;972.8832540095092;982.2878413150409;988.7033889833021;989.3322007644811;990.2226751999646;19610.70583941242;21635.810030448163;21679.891600541898;21700.891495542735;21729.782702173092;881.7575166829045;914.2046419614977;977.7457860956202;1007.0684616729496;1012.3065699995526;10071.690680211526;10139.949300253598;10181.898181018243;10458.895411045793;11121.388323642115;710023.4498827473;732668;740112;753824;755611;98769;144342;151979;155623;155836;988.4909271725068;988.5847943863595;1021.2238698588977;1023.7594165370983;1025.1286406554368;19559.902200488905;19757.40727778169;20407.591988122862;21340;21604.13583456644;48492.7575362135;57571.56071098256;58667.70666146642;62667;62942.055869161086;75974.62012689901;78590.60704696669;85280;87008.38974830766;89078;8130.918690813252;8295.170482951657;8461.873071904049;8586.699465518677;8589.398742087993;11605.535778568763;12973.156744811557;13172;13351.132176408502;13514.324283785574;11028.889711102785;11121.666350009513;11133.220674553027;11469.139814513777;11583.131265155316;10519.579216831547;11216.719582010515;11327.660170194578;11619.419029048344;11718.70703232426;11192.720181995515;11269.549218031514;11670.766584668429;11672.358020308964;11755.471003804678;7033.507654464343;7581.962090189514;7644.770656880302;8410.831783364421;8578.227959483676;8431.32549396059;10384.792304154025;11296.83054754163;11651.88348116508;11736.413179341169 09:37:30 INFO - PROCESS | 1645 | |3;mootools.html;1402.8106205662232;1412.103314395359;1429.8927580431466;1433.5770947570466;1434.146682723779;7949.562774047425;8352.874706879402;8385.496870187793;8386.119457456964;8386.371022173338;7296.817579560514;7572.12920514141;7594.43041771868;7875.80310492238;7986.840263194733;2207.4150350157183;2249.134083377895;2253.526759380524;2253.5943530164564;2254.4701995031155;2175.347395781267;2263.5020295534955;2282.8972696228625;2288.21056735426;2288.622377307742;2002.3392280547384;2090.3519908828234;2092.487340601555;2204.7574766775642;2294.9655755163685;2262.3891549281766;2291.873946932935;2292.449812045114;2292.5414917016665;2293.3693234360517;2228.3983324502456;2306.6770652108717;2311.884405779704;2316.3861576682193;2319.9188028418926;7093.467989900726;8142.43002989791;8225.629846656915;8229.753107406787;8273.296769774577;4996.575291100261;5149.407818100929;5158.303629010074;5162.199859021857;5164.406093299281;3213.935721285561;3275.1157187559465;3309.635940046593;3482.2165012872156;3485.9651403485896;1187.5012494752225;1194.8386967759327;1216.6958260434892;1229.3668760588305;1246.9875301246961;10491;10567.524461399256;10575.524101415454;10613.363198208132;10616.469176541143;4041.777702226347;4337.327714204303;4532.478764942008;4579.977100114478;4580.702254353457;9332.90667093334;9344.486053267136;9353.672621458216;9353.766155846022;9367.344285899922;5190.455002224712;5193.221016847428;5195.766190521435;5195.9740201298755;5196.896062078813;4658.627309815207;4680.063987202573;4724.740139292372;4725.692829966041;4790.736509491941;3724.571674257495;3741.7380783344906;3798.506194194737;3823.044238940265;3862.6523612874976;1254;1256.9120161588785;1257.1639859493516;1270.8093785931999;1274.7323062157052;709.7906117695293;757.7158565537924;764.9847003060019;784.8077221080872;842.2083241752733;5273.156294992783;5442.836714898559;5513.5037846594;5728.05487094633;5729.828105156852;977.7848873247874;978.5107446276861;984.4585477987222;1001.62439085343;1028.7603437857365;3180.7773455858332;3445.242046749711;3582.390993531106;3684.8157592120824;3750.2499500099543;9577.23382129442;9811.607535698635;9953.701388958343;9979.950100249598;9983.35108217978;9156.542172891328;9571.61713531451;9869.654562090293;9905.257105716955;9907.455089969977;7456.51532650376;7659.157492675801;7661.233876612406;7662.080550333995;7668.27151420603;5519.613627046068;5519.751611177427;5526.336839579276;5750.7987220447085;5854.882902342015;9104.13510716481;9208;9217.262618990331;9218.631254749735;9235.07649235071;7797.647011784987;7996.880046799186;9084.909810822479;9305.255579553756;9315.39449935752;6322.557420980487;6808.795736127924;7094.11323584552;7139.8572028560175;7157.463190260855;7798.181190974866;7799.961000194962;7887.723929662434;7934.60326983637;7989.680412783424;6865.382115609619;6961.756338528127;6998;7019.122609673791;7107.82230444243;6949.513534052568;6954.269801670752;6994.2306346302885;7003.54476958996;7011.1586609607175;5441.319835020622;6267.122602835516;6284.685765711788;6335.303116657256;6364.236291645032;4847.563719265283;4866.197077482176;4899.265110233494;4939.703617782944;5021.874453138701;4688.601468875047;4857.9028419432125;4957.975210123926;5050.469700681522;5060.2156665716875 09:37:30 INFO - PROCESS | 1645 | |4;prototype.html;360.2255151424437;361.681720086324;369.6839202481878;375.96428339307755;377.0968530369766;5296.205569164627;5336.573074154068;5353.0193828283545;5356.919646205305;5399.868076515611;4766.952330476694;4772.952270477294;5117.232415137732;5164.483551644834;5302.844807339637;3627.891163265106;4100.2209580179815;4175.394567787669;4194.266003449399;4204.747715137086;3911.3741801311794;3931.351327031051;4137.97931010346;4141.9438043298915;4161.729487583298;3984.980075099635;4112.527059388164;4126.546079931205;4153.54311025787;4188.350805625118;4090.734102283342;4110.486189226347;4117.629413352813;4150.688698347606;4176.245976689783;3650.5984341722387;3669.486271921933;3672.816359182057;3679.9264014719824;3684;4915.950840491585;5157.252198431244;5213.9217911730975;5217;5229.764660590245;4380.759058251795;4547.3179023146795;4812.711237325737;4828.939680700698;4834.066373450612;4623.8150473981;5348.8127915523155;5605.355384130837;5608.130739735348;5633.633813802131;4076.65470394772;4092.626276759472;4106.07613287011;4109.137410833807;4148;5202.869928251824;5388.7305634718105;5434.347878254595;5868.442497962772;5872.034622515645;3324.4096375913955;3858.324793161242;3893.7079719021185;3907.159960608483;3908.7068469864876;3168.445522033635;3878;3965.4448377227213;3971.324874771296;3979.2041591681777;4210.621044105984;4427.80074896624;4589.9213684784045;4601.883133758509;4626.629869610423;4951.801927922915;4991.401031876198;5420.34955805298;5623.943760562423;5640.943590564042;1121.9046381057537;1156.7744289863397;1235.5922545559943;1275.953717951271;1276.942537585811;752.9472936894474;819.8155415031689;839.7522730794308;839.9076101628816;848.9812225329506;4299.720518166309;4717.386739723814;4914.680545764514;5003.974185292006;5055.615769205784;4761.738139393064;5056.064628043824;5225.16397376418;5521.282233309725;5547.861303467445;4517.887052823706;4829.203181475087;4993.375828021497;5240.895182096337;5361.008213480519;2510;2638.9208323750318;2861.2703760541344;2878.5394336905993;2878.7265209805037;4754.95245047552;5553.361363443175;5563.972180139073;5777.45114214149;5791.8552036199435;4541.682911955562;4617.1300623247635;5215.53060224574;5480.6437581557075;5769.88460230793;5193.935243275044;5424.907802552586;5437.4562543745305;5628.465295796893;5737.770489180386;4824.975875120602;5154.922401434659;5164.586833053271;5185.844424667266;5362.0668984535105;4362.709577471853;4808.543188397097;5289.233061206027;5310.761015754378;5471.343438787371;5297.390800058042;5444.455554444524;5494.450554944474;5708.315002199763;5727.825795711786;3179.586653735;3537.327907697529;3655.9817200913826;3656.433252845813;3666.651668091527;5673.262475878109;5753.482186603226;5786.016377215799;5796.797217057491;5797.101449275369;4747.406574178228;4756.500567440369;5215.81744638951;5407.297051383295;5593.965116453469;4903.239997800347;5364.7049412282695;5608.7195640217005;5617.606767526234;5641.717914104361;5250.527452529291;5395.757190926496;5475.397706252389;5491.670499770026;5542.889142217214;4862.756862156949;4937.259411088366;5087.92368114486;5528.612997090165;5585.916211256753;5173.560247378864;5423.945760542502;5433.076377015996;5441.646292990943;5458.454154584669 09:37:30 INFO - PROCESS | 1645 | |5;yui.html;704.3555147040458;727.7998550398639;755.440973679477;822.7572866004529;833.1793183714043;14931.850681493182;15634.061956282616;15640.84359156408;15651.217439128055;15671.921640391782;14268.785968210459;14350.569482915529;14503.347349369278;14599.708005839877;14689.485867994623;840.508302642953;869.9391042627019;874.4796845876725;875.2822685397977;877.5173654490037;871.877937088808;873.7247766953421;874.5408660453281;874.8818909447207;875.5578432891381;721.2282857342645;750.692216191359;758.9506682065676;865.2083343740501;885.2829208341265;908.3913777768911;910.2899738204211;912.6577533424966;914.9176574108362;919.2094798473374;685.3112621815044;685.7120009595932;687.2062767503542;689.4243306838796;690.823839920822;12062.577809776616;12388.81416778749;14836.925815370962;14880.181590012546;15355.4625588105;13638;14125.78811317831;14205.573832785032;14350.425982960669;14400.207988560625;11872.821907671392;11905.940470297679;11981.580644677395;12096.697582560506;12226.877731222663;3685.8157092145284;3772.4152756322815;3779.239656541731;3846.673032792216;3854.3640299350855;105200;108740.91259087386;111736;112547;113120;10659.520321585547;11050.392228427514;11136.276142050741;11154.553817847196;11312;21881.890590547264;22364.99357528915;22655.546889062127;22660.980255888524;22674.546509069725;7567.772966811004;7635.618219089134;7645.694172233048;7718.691252349955;7824.139344672081;4149.97925010373;4246.702730808814;4300.720453170534;4378.824847006148;4398.494173170063;3497.213127046394;3604.513390692223;3609.1698909251018;3613.0425437259146;3623.0217841182734;591;635.0788863687291;654.7839213059679;656.3666062249952;661.9139511863427;645.5868244323636;657.5495785386929;691.5746815708303;695.8086526205334;710.2684235237714;9457.881319293043;9891.901080989242;10097.141742951932;10124.54439550222;10569.524371403306;662.559398000334;681.3356976947437;695.5409429776424;704.6969802984765;708.2174197511779;4885.285595205011;4993.850184494471;5002.024605202024;5002.224655178453;5004.099262132851;108379.45810270899;108766.45616772024;109405;109863;112365.43817280862;81419.5929020363;87772.56113719518;95780.15212241806;97805;100221;81628.77556836652;83803;85277;85807.99999999875;87008.12991870126;9588.76028099303;9597.568109434953;9609.56756945953;9648.372855764354;9734.707958760966;46556;51274.743626283125;51296.74351628218;51382.66012709162;51711.74144129255;66449.66679752796;66709.33290667032;67879.66060169865;68593.65703171452;69274.65362673154;11651.359175245103;12301.5951846481;12333.260004399765;12895.613131606067;12909.160904541175;14656.413743450568;14666.985817725226;14859.702805943605;14890.404383824958;15582.220888955733;13594.524191653245;13667.794983075062;13806.930965345511;13811.585652430444;13834.446622135001;13686.726265474834;13975.440982360999;14078.577642670736;14088.084274522123;14264.286785660883;13073.803892941803;13501;13519.391627376988;14017;14060.507882224074;10996.340219586851;11198.16013798952;11363.829542557034;11415.771684566429;11542.653720388402;7817.452778305692;10916.781664366827;10995.505202266077;11053.557857685602;11314.151438642299 09:37:30 INFO - PROCESS | 1645 | __end_tp_report 09:37:30 INFO - PROCESS | 1645 | __start_cc_report 09:37:30 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2756 09:37:30 INFO - PROCESS | 1645 | __end_cc_report 09:37:30 INFO - PROCESS | 1645 | __startTimestamp1485884250012__endTimestamp 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | ------- Summary: start ------- 09:37:30 INFO - PROCESS | 1645 | Number of tests: 6 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5724.15 Median:5937.61 stddev:669.11 (11.3%) stddev-sans-first:643.38 09:37:30 INFO - PROCESS | 1645 | Values: 3292.1 3311.1 3316.5 3329.3 3366.4 5600.2 5657.5 5731.7 5775.7 5805.6 5326.9 5677.1 5705.0 5718.9 5727.1 4371.5 5143.3 5155.9 5160.6 5201.4 5238.9 5240.7 5243.2 5259.6 5267.2 5222.5 5237.5 5237.7 5238.6 5250.5 5237.5 5247.6 5247.8 5250.0 5252.9 4350.9 5097.2 5212.6 5218.1 5226.5 4691.6 5675.9 5729.4 5737.7 5745.7 6086.9 6091.4 6093.6 6104.8 6112.8 6162.7 6163.4 6221.6 6226.5 6265.2 5817.9 5821.7 5828.1 5831.6 5858.3 6331.6 6365.3 6367.9 6380.5 6396.3 6065.1 6130.1 6131.8 6133.8 6134.5 6171.6 6214.0 6215.6 6229.2 6293.1 6146.9 6174.0 6221.7 6222.6 6259.3 6311.7 6321.0 6322.3 6329.8 6346.4 4857.2 4909.3 4912.9 4956.5 4987.4 4389.1 4434.4 4438.2 4438.5 4449.2 5768.6 5901.7 5908.8 5911.8 5945.8 6298.4 6348.4 6358.7 6374.3 6385.2 5678.7 5727.9 5744.7 5882.2 5892.5 4508.8 5402.4 5440.9 5505.2 5539.2 5617.0 6058.0 6314.2 6321.2 6346.8 6229.0 6323.5 6342.0 6348.5 6360.0 6238.1 6256.0 6258.3 6288.9 6335.4 5188.6 6231.1 6240.8 6250.8 6264.0 5685.9 6067.9 6253.7 6278.7 6286.6 5602.6 5992.3 6034.8 6053.0 6061.7 6084.5 6087.4 6101.2 6111.8 6114.1 5920.7 5931.9 5935.4 5941.8 5957.5 6046.2 6070.2 6072.7 6081.1 6112.1 5903.4 5937.6 5960.9 6021.3 6032.0 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:66160.55 Median:38147.93 stddev:139211.27 (364.9%) stddev-sans-first:139514.33 09:37:30 INFO - PROCESS | 1645 | Values: 434.6 490.5 493.2 493.7 516.2 30482.7 38910.6 41270.0 41658.8 43371.6 42193.0 42468.9 42553.0 42680.0 42807.6 8338.7 9137.1 9326.1 9348.0 9349.0 8372.1 10890.3 11215.5 11217.8 11362.9 11089.3 11136.8 11154.7 11168.0 11173.9 8311.2 8555.7 11094.7 11150.6 11155.7 10703.6 11016.4 11034.8 11198.4 11236.5 40369.8 40759.6 40790.2 40927.6 41028.0 3716.2 3986.5 3992.1 4019.8 4028.7 30368.5 30858.5 31023.4 31059.2 31134.9 3149.4 3213.3 3256.0 3316.8 3330.0 375040.0 375323.0 375565.0 376870.0 492387.0 62250.1 62521.0 62701.0 62701.7 62791.0 60051.1 61882.0 61945.0 62132.4 62219.7 16056.6 16293.4 16293.6 16320.3 16321.7 11121.9 11424.0 11428.2 12533.0 12610.1 13491.5 15987.8 16193.9 17848.6 18319.6 5265.8 6507.7 6526.3 6558.7 6572.7 830.8 853.2 859.8 863.7 870.8 29514.7 29892.4 29923.0 30790.0 30906.1 1021.6 1030.2 1048.4 1053.8 1191.0 15013.0 15486.4 15529.2 15715.9 15901.9 601448.0 606808.0 607281.0 608116.0 608724.0 60942.7 61135.7 61171.0 61296.0 61392.7 1251.4 1371.5 1394.4 1418.9 1435.3 28425.7 29341.9 29364.7 29966.3 30198.9 52270.7 52621.7 52647.0 52776.5 52790.0 535993.0 536274.0 536596.0 538233.0 539686.0 39912.0 40426.6 40728.6 40890.6 41217.8 45899.5 46444.8 46812.1 47410.3 47653.3 37232.4 37939.0 38327.6 39133.4 39531.0 37696.4 38348.4 39129.8 39174.0 39376.8 35826.8 37100.8 37373.0 37485.4 37968.2 31591.0 33103.7 33328.7 33360.3 33857.4 19917.4 24509.4 24716.6 24878.8 24983.4 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:40397.58 Median:13716.80 stddev:123092.16 (897.4%) stddev-sans-first:123400.77 09:37:30 INFO - PROCESS | 1645 | Values: 370.8 390.8 396.8 401.3 424.4 14844.6 15991.4 18966.0 20841.6 21496.0 20797.6 21027.4 21563.1 22218.6 22301.7 5614.1 5673.8 5747.7 5824.9 5920.6 4142.7 4367.7 5684.8 5802.6 5806.6 6154.4 6425.7 6445.0 6516.6 6534.3 6295.0 6442.0 6495.6 6517.3 6543.5 5697.4 5714.4 5718.7 5724.2 5734.8 23276.9 23962.9 24365.6 24935.9 25377.9 2497.7 2544.0 2553.7 2577.6 2587.1 8570.9 10131.8 10140.6 10265.7 10569.6 1817.7 1868.6 1879.6 1897.5 1898.4 140535.0 143138.0 144051.0 145443.0 147420.0 11649.9 11736.4 11831.3 11888.5 11989.6 15678.3 15718.8 15733.8 15735.7 15736.1 8342.5 8685.5 8724.2 8747.7 8768.9 8366.6 8385.2 8403.0 8553.9 8560.7 4657.9 4750.7 4753.3 4820.6 4841.7 1966.3 2010.5 2028.2 2029.2 2032.2 972.9 982.3 988.7 989.3 990.2 19610.7 21635.8 21679.9 21700.9 21729.8 881.8 914.2 977.7 1007.1 1012.3 10071.7 10139.9 10181.9 10458.9 11121.4 710023.4 732668.0 740112.0 753824.0 755611.0 98769.0 144342.0 151979.0 155623.0 155836.0 988.5 988.6 1021.2 1023.8 1025.1 19559.9 19757.4 20407.6 21340.0 21604.1 48492.8 57571.6 58667.7 62667.0 62942.1 75974.6 78590.6 85280.0 87008.4 89078.0 8130.9 8295.2 8461.9 8586.7 8589.4 11605.5 12973.2 13172.0 13351.1 13514.3 11028.9 11121.7 11133.2 11469.1 11583.1 10519.6 11216.7 11327.7 11619.4 11718.7 11192.7 11269.5 11670.8 11672.4 11755.5 7033.5 7582.0 7644.8 8410.8 8578.2 8431.3 10384.8 11296.8 11651.9 11736.4 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5329.33 Median:5196.44 stddev:2892.97 (55.7%) stddev-sans-first:2886.04 09:37:30 INFO - PROCESS | 1645 | Values: 1402.8 1412.1 1429.9 1433.6 1434.1 7949.6 8352.9 8385.5 8386.1 8386.4 7296.8 7572.1 7594.4 7875.8 7986.8 2207.4 2249.1 2253.5 2253.6 2254.5 2175.3 2263.5 2282.9 2288.2 2288.6 2002.3 2090.4 2092.5 2204.8 2295.0 2262.4 2291.9 2292.4 2292.5 2293.4 2228.4 2306.7 2311.9 2316.4 2319.9 7093.5 8142.4 8225.6 8229.8 8273.3 4996.6 5149.4 5158.3 5162.2 5164.4 3213.9 3275.1 3309.6 3482.2 3486.0 1187.5 1194.8 1216.7 1229.4 1247.0 10491.0 10567.5 10575.5 10613.4 10616.5 4041.8 4337.3 4532.5 4580.0 4580.7 9332.9 9344.5 9353.7 9353.8 9367.3 5190.5 5193.2 5195.8 5196.0 5196.9 4658.6 4680.1 4724.7 4725.7 4790.7 3724.6 3741.7 3798.5 3823.0 3862.7 1254.0 1256.9 1257.2 1270.8 1274.7 709.8 757.7 765.0 784.8 842.2 5273.2 5442.8 5513.5 5728.1 5729.8 977.8 978.5 984.5 1001.6 1028.8 3180.8 3445.2 3582.4 3684.8 3750.2 9577.2 9811.6 9953.7 9980.0 9983.4 9156.5 9571.6 9869.7 9905.3 9907.5 7456.5 7659.2 7661.2 7662.1 7668.3 5519.6 5519.8 5526.3 5750.8 5854.9 9104.1 9208.0 9217.3 9218.6 9235.1 7797.6 7996.9 9084.9 9305.3 9315.4 6322.6 6808.8 7094.1 7139.9 7157.5 7798.2 7800.0 7887.7 7934.6 7989.7 6865.4 6961.8 6998.0 7019.1 7107.8 6949.5 6954.3 6994.2 7003.5 7011.2 5441.3 6267.1 6284.7 6335.3 6364.2 4847.6 4866.2 4899.3 4939.7 5021.9 4688.6 4857.9 4958.0 5050.5 5060.2 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4458.84 Median:4944.53 stddev:1334.17 (27.0%) stddev-sans-first:1301.96 09:37:30 INFO - PROCESS | 1645 | Values: 360.2 361.7 369.7 376.0 377.1 5296.2 5336.6 5353.0 5356.9 5399.9 4767.0 4773.0 5117.2 5164.5 5302.8 3627.9 4100.2 4175.4 4194.3 4204.7 3911.4 3931.4 4138.0 4141.9 4161.7 3985.0 4112.5 4126.5 4153.5 4188.4 4090.7 4110.5 4117.6 4150.7 4176.2 3650.6 3669.5 3672.8 3679.9 3684.0 4916.0 5157.3 5213.9 5217.0 5229.8 4380.8 4547.3 4812.7 4828.9 4834.1 4623.8 5348.8 5605.4 5608.1 5633.6 4076.7 4092.6 4106.1 4109.1 4148.0 5202.9 5388.7 5434.3 5868.4 5872.0 3324.4 3858.3 3893.7 3907.2 3908.7 3168.4 3878.0 3965.4 3971.3 3979.2 4210.6 4427.8 4589.9 4601.9 4626.6 4951.8 4991.4 5420.3 5623.9 5640.9 1121.9 1156.8 1235.6 1276.0 1276.9 752.9 819.8 839.8 839.9 849.0 4299.7 4717.4 4914.7 5004.0 5055.6 4761.7 5056.1 5225.2 5521.3 5547.9 4517.9 4829.2 4993.4 5240.9 5361.0 2510.0 2638.9 2861.3 2878.5 2878.7 4755.0 5553.4 5564.0 5777.5 5791.9 4541.7 4617.1 5215.5 5480.6 5769.9 5193.9 5424.9 5437.5 5628.5 5737.8 4825.0 5154.9 5164.6 5185.8 5362.1 4362.7 4808.5 5289.2 5310.8 5471.3 5297.4 5444.5 5494.5 5708.3 5727.8 3179.6 3537.3 3656.0 3656.4 3666.7 5673.3 5753.5 5786.0 5796.8 5797.1 4747.4 4756.5 5215.8 5407.3 5594.0 4903.2 5364.7 5608.7 5617.6 5641.7 5250.5 5395.8 5475.4 5491.7 5542.9 4862.8 4937.3 5087.9 5528.6 5585.9 5173.6 5423.9 5433.1 5441.6 5458.5 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:21124.92 Median:3812.96 stddev:30920.87 (810.9%) stddev-sans-first:30969.60 09:37:30 INFO - PROCESS | 1645 | Values: 704.4 727.8 755.4 822.8 833.2 14931.9 15634.1 15640.8 15651.2 15671.9 14268.8 14350.6 14503.3 14599.7 14689.5 840.5 869.9 874.5 875.3 877.5 871.9 873.7 874.5 874.9 875.6 721.2 750.7 759.0 865.2 885.3 908.4 910.3 912.7 914.9 919.2 685.3 685.7 687.2 689.4 690.8 12062.6 12388.8 14836.9 14880.2 15355.5 13638.0 14125.8 14205.6 14350.4 14400.2 11872.8 11905.9 11981.6 12096.7 12226.9 3685.8 3772.4 3779.2 3846.7 3854.4 105200.0 108740.9 111736.0 112547.0 113120.0 10659.5 11050.4 11136.3 11154.6 11312.0 21881.9 22365.0 22655.5 22661.0 22674.5 7567.8 7635.6 7645.7 7718.7 7824.1 4150.0 4246.7 4300.7 4378.8 4398.5 3497.2 3604.5 3609.2 3613.0 3623.0 591.0 635.1 654.8 656.4 661.9 645.6 657.5 691.6 695.8 710.3 9457.9 9891.9 10097.1 10124.5 10569.5 662.6 681.3 695.5 704.7 708.2 4885.3 4993.9 5002.0 5002.2 5004.1 108379.5 108766.5 109405.0 109863.0 112365.4 81419.6 87772.6 95780.2 97805.0 100221.0 81628.8 83803.0 85277.0 85808.0 87008.1 9588.8 9597.6 9609.6 9648.4 9734.7 46556.0 51274.7 51296.7 51382.7 51711.7 66449.7 66709.3 67879.7 68593.7 69274.7 11651.4 12301.6 12333.3 12895.6 12909.2 14656.4 14667.0 14859.7 14890.4 15582.2 13594.5 13667.8 13806.9 13811.6 13834.4 13686.7 13975.4 14078.6 14088.1 14264.3 13073.8 13501.0 13519.4 14017.0 14060.5 10996.3 11198.2 11363.8 11415.8 11542.7 7817.5 10916.8 10995.5 11053.6 11314.2 09:37:30 INFO - PROCESS | 1645 | -------- Summary: end -------- 09:37:30 INFO - PROCESS | 1645 | 09:37:30 INFO - TEST-INFO | 1645: exit 0 09:37:30 INFO - TEST-OK | dromaeo_css | took 1119656ms 09:37:30 INFO - TEST-START | kraken 09:37:30 INFO - operating with platform_type : mac_ 09:37:30 INFO - Initialising browser for kraken test... 09:37:30 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpg3HVJR/profile http://localhost:49243/getInfo.html) 09:37:32 INFO - PROCESS | 1656 | 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] 09:37:33 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 09:37:33 INFO - PROCESS | 1656 | colorDepth:24 09:37:33 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 09:37:33 INFO - PROCESS | 1656 | __metrics 09:37:33 INFO - PROCESS | 1656 | 1485884253197 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:37:33 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:37:33 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:33 INFO - TEST-INFO | 1656: exit 0 09:37:33 INFO - Browser initialized. 09:37:33 INFO - Running cycle 1/1 for kraken test... 09:37:33 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpg3HVJR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:45 INFO - PROCESS | 1657 | RSS: Main: 236998656 09:37:45 INFO - PROCESS | 1657 | 09:37:48 INFO - PROCESS | 1657 | 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) 09:37:49 INFO - PROCESS | 1657 | RSS: Main: 288935936 09:37:49 INFO - PROCESS | 1657 | 09:37:52 INFO - PROCESS | 1657 | 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) 09:37:53 INFO - PROCESS | 1657 | RSS: Main: 290541568 09:37:53 INFO - PROCESS | 1657 | 09:37:56 INFO - PROCESS | 1657 | 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) 09:37:56 INFO - PROCESS | 1657 | RSS: Main: 382926848 09:37:56 INFO - PROCESS | 1657 | 09:37:59 INFO - PROCESS | 1657 | 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) 09:37:59 INFO - PROCESS | 1657 | RSS: Main: 283086848 09:37:59 INFO - PROCESS | 1657 | 09:38:01 INFO - PROCESS | 1657 | 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) 09:38:02 INFO - PROCESS | 1657 | RSS: Main: 287744000 09:38:02 INFO - PROCESS | 1657 | 09:38:05 INFO - PROCESS | 1657 | 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) 09:38:05 INFO - PROCESS | 1657 | RSS: Main: 330596352 09:38:05 INFO - PROCESS | 1657 | 09:38:08 INFO - PROCESS | 1657 | 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) 09:38:09 INFO - PROCESS | 1657 | RSS: Main: 331993088 09:38:09 INFO - PROCESS | 1657 | 09:38:12 INFO - PROCESS | 1657 | 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) 09:38:12 INFO - PROCESS | 1657 | RSS: Main: 329580544 09:38:12 INFO - PROCESS | 1657 | 09:38:14 INFO - PROCESS | 1657 | 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) 09:38:14 INFO - PROCESS | 1657 | RSS: Main: 305467392 09:38:14 INFO - PROCESS | 1657 | 09:38:16 INFO - PROCESS | 1657 | 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) 09:38:17 INFO - PROCESS | 1657 | RSS: Main: 308584448 09:38:17 INFO - PROCESS | 1657 | 09:38:19 INFO - PROCESS | 1657 | 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) 09:38:20 INFO - PROCESS | 1657 | RSS: Main: 310284288 09:38:20 INFO - PROCESS | 1657 | 09:38:23 INFO - PROCESS | 1657 | 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) 09:38:24 INFO - PROCESS | 1657 | RSS: Main: 322486272 09:38:24 INFO - PROCESS | 1657 | 09:38:27 INFO - PROCESS | 1657 | 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) 09:38:27 INFO - PROCESS | 1657 | RSS: Main: 321646592 09:38:27 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | 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) 09:38:29 INFO - PROCESS | 1657 | RSS: Main: 366604288 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | __start_tp_report 09:38:29 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:38:29 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:38:29 INFO - PROCESS | 1657 | |i|pagename|runs| 09:38:29 INFO - PROCESS | 1657 | |0;ai-astar;113;114;120;163;170;114;115;114;114;115 09:38:29 INFO - PROCESS | 1657 | |1;audio-beat-detection;139;109;108;165;141;110;108;113;158;112 09:38:29 INFO - PROCESS | 1657 | |2;audio-dft;181;184;186;185;184;182;185;183;183;184 09:38:29 INFO - PROCESS | 1657 | |3;audio-fft;80;58;57;88;61;57;59;74;96;80 09:38:29 INFO - PROCESS | 1657 | |4;audio-oscillator;86;71;86;70;69;70;85;69;69;82 09:38:29 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;88;86;87;86;87;87;88;86;88;85 09:38:29 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;93;93;94;93;99;94;94;93;122 09:38:29 INFO - PROCESS | 1657 | |7;imaging-desaturate;82;84;85;88;87;92;87;86;85;87 09:38:29 INFO - PROCESS | 1657 | |8;json-parse-financial;61;60;59;59;60;59;59;59;59;61 09:38:29 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;64;73;72;77;70;69;69;70;70;70 09:38:29 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;87;87;86;87;84;88;107;105;83;88 09:38:29 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;142;146;145;139;138;140;139;141;242;154 09:38:29 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;174;209;175;176;170;173;176;174;173;175 09:38:29 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;69;66;60;69;81;86;84;87;66 09:38:29 INFO - PROCESS | 1657 | __end_tp_report 09:38:29 INFO - PROCESS | 1657 | __start_cc_report 09:38:29 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2043 09:38:29 INFO - PROCESS | 1657 | __end_cc_report 09:38:29 INFO - PROCESS | 1657 | __startTimestamp1485884309824__endTimestamp 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:38:29 INFO - PROCESS | 1657 | Number of tests: 14 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:125.20 Median:115.00 stddev:21.91 (19.1%) stddev-sans-first:22.79 09:38:29 INFO - PROCESS | 1657 | Values: 113.0 114.0 120.0 163.0 170.0 114.0 115.0 114.0 114.0 115.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:126.00 stddev:22.35 (17.7%) stddev-sans-first:23.23 09:38:29 INFO - PROCESS | 1657 | Values: 139.0 109.0 108.0 165.0 141.0 110.0 108.0 113.0 158.0 112.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.22 09:38:29 INFO - PROCESS | 1657 | Values: 181.0 184.0 186.0 185.0 184.0 182.0 185.0 183.0 183.0 184.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:71.00 Median:77.00 stddev:14.49 (18.8%) stddev-sans-first:15.00 09:38:29 INFO - PROCESS | 1657 | Values: 80.0 58.0 57.0 88.0 61.0 57.0 59.0 74.0 96.0 80.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:7.89 (10.3%) stddev-sans-first:7.43 09:38:29 INFO - PROCESS | 1657 | Values: 86.0 71.0 86.0 70.0 69.0 70.0 85.0 69.0 69.0 82.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:1.03 (1.2%) stddev-sans-first:1.00 09:38:29 INFO - PROCESS | 1657 | Values: 88.0 86.0 87.0 86.0 87.0 87.0 88.0 86.0 88.0 85.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:94.00 stddev:9.00 (9.6%) stddev-sans-first:9.48 09:38:29 INFO - PROCESS | 1657 | Values: 94.0 93.0 93.0 94.0 93.0 99.0 94.0 94.0 93.0 122.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:87.00 stddev:2.67 (3.1%) stddev-sans-first:2.33 09:38:29 INFO - PROCESS | 1657 | Values: 82.0 84.0 85.0 88.0 87.0 92.0 87.0 86.0 85.0 87.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:59.50 stddev:0.84 (1.4%) stddev-sans-first:0.73 09:38:29 INFO - PROCESS | 1657 | Values: 61.0 60.0 59.0 59.0 60.0 59.0 59.0 59.0 59.0 61.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.00 stddev:3.31 (4.7%) stddev-sans-first:2.57 09:38:29 INFO - PROCESS | 1657 | Values: 64.0 73.0 72.0 77.0 70.0 69.0 69.0 70.0 70.0 70.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:87.00 stddev:8.50 (9.8%) stddev-sans-first:8.93 09:38:29 INFO - PROCESS | 1657 | Values: 87.0 87.0 86.0 87.0 84.0 88.0 107.0 105.0 83.0 88.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:143.50 stddev:31.77 (22.1%) stddev-sans-first:33.47 09:38:29 INFO - PROCESS | 1657 | Values: 142.0 146.0 145.0 139.0 138.0 140.0 139.0 141.0 242.0 154.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:175.00 stddev:11.21 (6.4%) stddev-sans-first:11.82 09:38:29 INFO - PROCESS | 1657 | Values: 174.0 209.0 175.0 176.0 170.0 173.0 176.0 174.0 173.0 175.0 09:38:29 INFO - PROCESS | 1657 | 09:38:29 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:75.00 stddev:9.90 (13.2%) stddev-sans-first:10.22 09:38:29 INFO - PROCESS | 1657 | Values: 67.0 69.0 66.0 60.0 69.0 81.0 86.0 84.0 87.0 66.0 09:38:29 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:38:29 INFO - PROCESS | 1657 | 09:38:30 INFO - TEST-INFO | 1657: exit 0 09:38:30 INFO - TEST-OK | kraken | took 60040ms 09:38:30 INFO - SUITE-END | took 1179s 09:38:30 INFO - Completed test suite (00:19:39) 09:38:30 INFO - javascript benchmark 09:38:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7286.38756012291, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.0946739646597, 3311.056348940552, 3316.4693649016162, 3329.334133173366, 3366.4108780963325, 5600.1599760036015, 5657.462541058603, 5731.713414329282, 5775.653460792355, 5805.564582656302, 5326.946730532693, 5677.063284558053, 5704.971475142618, 5718.885622287552, 5727.083666613343, 4371.497277813044, 5143.331366922304, 5155.896882062375, 5160.612954028444, 5201.40183878855, 5238.895222095556, 5240.711760853152, 5243.187305967562, 5259.6055295852775, 5267.23625074364, 5222.451642577532, 5237.502437268454, 5237.7381130943495, 5238.633295669305, 5250.474952504738, 5237.528622423981, 5247.580193584504, 5247.842564723064, 5250.0, 5252.947470525303, 4350.934735978963, 5097.209932460474, 5212.556932660728, 5218.13900706383, 5226.477352264781, 4691.554302341272, 5675.914861277084, 5729.369769325371, 5737.655740655574, 5745.741441635137, 6086.939130608682, 6091.360407157229, 6093.634381937098, 6104.755809767604, 6112.789476565617, 6162.7226774795245, 6163.445289923883, 6221.595596286227, 6226.470749986257, 6265.154204182468, 5817.941820581825, 5821.650700957956, 5828.0966450200285, 5831.591788574844, 5858.297004359504, 6331.620102793847, 6365.2998170201245, 6367.872642547123, 6380.521460890452, 6396.328385519453, 6065.090236464565, 6130.080487926846, 6131.846703832351, 6133.754649813957, 6134.478569321657, 6171.6297022178815, 6213.96893015532, 6215.595986260879, 6229.221347331583, 6293.056041593706, 6146.938530614726, 6173.969130154321, 6221.688915554295, 6222.564420490613, 6259.3427690091885, 6311.684415779193, 6320.968395158086, 6322.304546499881, 6329.841753956096, 6346.428821406095, 4857.247126695368, 4909.2881532177635, 4912.8771780705765, 4956.529129732669, 4987.351644286219, 4389.056352884081, 4434.357018232338, 4438.201123797683, 4438.467383913951, 4449.221386257392, 5768.596198266165, 5901.734421951022, 5908.763649454059, 5911.76352945886, 5945.7621695132575, 6298.433141017239, 6348.365163483615, 6358.682065896779, 6374.298827129012, 6385.233771947396, 5678.659280443187, 5727.914081288784, 5744.655320680772, 5882.23530940975, 5892.469677729025, 4508.797104130322, 5402.35171779389, 5440.91838622421, 5505.201745746845, 5539.19681646159, 5617.045102332531, 6057.969710151421, 6314.210723659543, 6321.209848768812, 6346.841328966628, 6228.968855155696, 6323.462505687068, 6342.048692696132, 6348.5238607105575, 6360.014197799349, 6238.064290356301, 6256.0303153011355, 6258.342873998165, 6288.874222515433, 6335.366463353628, 5188.584913206859, 6231.096491008688, 6240.812775616739, 6250.843728906813, 6264.0, 5685.891251206141, 6067.908981365372, 6253.6560489173535, 6278.7488500461295, 6286.622802631857, 5602.607817452902, 5992.340842507408, 6034.849128771816, 6053.031514957585, 6061.72722227492, 6084.482818960261, 6087.391260873877, 6101.237345331833, 6111.786087486917, 6114.0828875669, 5920.73356698941, 5931.851703707269, 5935.376785437467, 5941.792037278675, 5957.523398128226, 6046.183765191643, 6070.2108725865355, 6072.696365181635, 6081.148639190428, 6112.144299798121, 5903.350631430625, 5937.614055086406, 5960.940390596039, 6021.307549631849, 6032.034874420072], "value": 5680.787703605902, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.57194663256695, 490.5486952004156, 493.15424047758086, 493.7062447843532, 516.2076213013025, 30482.69517304829, 38910.61089389109, 41269.96825079375, 41658.791706041506, 43371.56628433715, 42193.0, 42468.93827654303, 42553.0, 42680.0, 42807.571924280746, 8338.66645334189, 9137.131972462605, 9326.067393260653, 9348.0, 9349.0, 8372.120927302636, 10890.346579205234, 11215.485909402209, 11217.775644487105, 11362.863713628612, 11089.33463992159, 11136.777264454706, 11154.721131971724, 11168.0, 11173.944130279378, 8311.16888311164, 8555.657773689045, 11094.667159985172, 11150.553977840877, 11155.665330040112, 10703.625373111981, 11016.449177541172, 11034.834477482844, 11198.440077996067, 11236.49435775384, 40369.79815100935, 40759.592404076175, 40790.18419631591, 40927.590724092974, 41027.97430064272, 3716.21959388526, 3986.521617405901, 3992.0818211811447, 4019.799010049486, 4028.6978476614076, 30368.54447183294, 30858.537121943187, 31023.37953240945, 31059.22351941197, 31134.910278140156, 3149.3543823516125, 3213.2770126721534, 3255.9837200813845, 3316.7844090134304, 3329.9500507492407, 375039.99999999726, 375323.0, 375565.0, 376870.0, 492387.0, 62250.066249006304, 62521.0, 62701.0, 62701.68649156725, 62791.0, 60051.09923351153, 61882.0, 61945.0, 62132.378676213564, 62219.6889015552, 16056.598585035468, 16293.429729959631, 16293.59266018359, 16320.265588048565, 16321.673566528603, 11121.888781112248, 11424.0, 11428.2000259981, 12533.0, 12610.117291789487, 13491.460341586422, 15987.840121598867, 16193.919030404773, 17848.55378615545, 18319.633607327778, 5265.842024739264, 6507.665928484744, 6526.314736952551, 6558.737650493927, 6572.737090516422, 830.8130670599066, 853.2022558907441, 859.8065435277013, 863.667488017118, 870.8084221471266, 29514.704852951625, 29892.402151956838, 29923.0, 30790.0, 30906.0728178155, 1021.596469394585, 1030.1655658916302, 1048.3762161513887, 1053.8419237114495, 1190.9579118271513, 15013.024153430211, 15486.380544778083, 15529.223538823013, 15715.921420392824, 15901.853249811358, 601448.0, 606808.0, 607281.0, 608116.0, 608724.0, 60942.69528652328, 61135.694321528106, 61171.0, 61296.0, 61392.69303653453, 1251.4243448014015, 1371.5131128449457, 1394.3864699532173, 1418.936147873348, 1435.2823588205897, 28425.715742842305, 29341.85329073341, 29364.7063529362, 29966.25084372908, 30198.943036993598, 52270.73864630653, 52621.684457888856, 52647.0, 52776.47223527869, 52790.0, 535993.0, 536274.0, 536596.0, 538233.0, 539686.0, 39912.00219994524, 40426.59573404228, 40728.59271407248, 40890.59109408987, 41217.793911030254, 45899.541004589526, 46444.83887902829, 46812.063758725315, 47410.28884566803, 47653.28520072271, 37232.44151337786, 37939.0, 38327.616723832405, 39133.41299880447, 39531.0, 37696.43455348117, 38348.44267135085, 39129.804350979204, 39174.0, 39376.803115985385, 35826.820865896545, 37100.81449592735, 37373.0, 37485.43771843479, 37968.24063518769, 31591.0, 33103.70230261586, 33328.66671333255, 33360.33279334448, 33857.3978074283, 19917.402477925687, 24509.38726531851, 24716.629250560898, 24878.751212487645, 24983.376745442194], "value": 20027.36205224663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.84053856841547, 390.84756944791536, 396.8075483390553, 401.2984943838126, 424.39311784148674, 14844.628884277898, 15991.360345586178, 18966.0, 20841.583168336663, 21496.032678529464, 20797.584048319026, 21027.36917892462, 21563.13747450104, 22218.555628887414, 22301.665475017846, 5614.129809879455, 5673.773049078032, 5747.683877386742, 5824.941750582482, 5920.615160014585, 4142.71001029928, 4367.694261401703, 5684.772609095631, 5802.564807639423, 5806.59353845231, 6154.415330543591, 6425.742970281207, 6445.033245013238, 6516.641584712839, 6534.281229064799, 6294.999999999977, 6442.0014897690935, 6495.642739649317, 6517.348265173492, 6543.476521878239, 5697.401772813878, 5714.371419143891, 5718.714064296769, 5724.227229324029, 5734.770609175628, 23276.883615581814, 23962.880185599133, 24365.63451548228, 24935.87532062328, 25377.873110634515, 2497.725250222474, 2543.974560254411, 2553.7190908999996, 2577.561814491541, 2587.0815860369485, 8570.914290857074, 10131.848022279672, 10140.594376224943, 10265.692029239135, 10569.63006294776, 1817.6637322095455, 1868.5702288473726, 1879.6146789908037, 1897.4781934967857, 1898.3545594497798, 140535.0, 143138.0, 144051.0, 145443.0, 147420.0, 11649.941750291364, 11736.417988042616, 11831.290122592673, 11888.465019074163, 11989.64031079069, 15678.294476748573, 15718.764218536731, 15733.842661573237, 15735.68528629444, 15736.134512601917, 8342.499450033018, 8685.522296273639, 8724.171203735636, 8747.65009399617, 8768.912310876809, 8366.623501942428, 8385.203405676451, 8403.0, 8553.871691924625, 8560.743177704679, 4657.86026419208, 4750.714957102516, 4753.263244197155, 4820.6143508519235, 4841.709497430095, 1966.2724791827115, 2010.547626783962, 2028.198861449719, 2029.1781828359537, 2032.2074390987527, 972.8832540095092, 982.2878413150409, 988.7033889833021, 989.3322007644811, 990.2226751999646, 19610.70583941242, 21635.810030448163, 21679.891600541898, 21700.891495542735, 21729.782702173092, 881.7575166829045, 914.2046419614977, 977.7457860956202, 1007.0684616729496, 1012.3065699995526, 10071.690680211526, 10139.949300253598, 10181.898181018243, 10458.895411045793, 11121.388323642115, 710023.4498827473, 732668.0, 740112.0, 753824.0, 755611.0, 98769.0, 144342.0, 151979.0, 155623.0, 155836.0, 988.4909271725068, 988.5847943863595, 1021.2238698588977, 1023.7594165370983, 1025.1286406554368, 19559.902200488905, 19757.40727778169, 20407.591988122862, 21340.0, 21604.13583456644, 48492.7575362135, 57571.56071098256, 58667.70666146642, 62667.0, 62942.055869161086, 75974.62012689901, 78590.60704696669, 85280.0, 87008.38974830766, 89078.0, 8130.918690813252, 8295.170482951657, 8461.873071904049, 8586.699465518677, 8589.398742087993, 11605.535778568763, 12973.156744811557, 13172.0, 13351.132176408502, 13514.324283785574, 11028.889711102785, 11121.666350009513, 11133.220674553027, 11469.139814513777, 11583.131265155316, 10519.579216831547, 11216.719582010515, 11327.660170194578, 11619.419029048344, 11718.70703232426, 11192.720181995515, 11269.549218031514, 11670.766584668429, 11672.358020308964, 11755.471003804678, 7033.507654464343, 7581.962090189514, 7644.770656880302, 8410.831783364421, 8578.227959483676, 8431.32549396059, 10384.792304154025, 11296.83054754163, 11651.88348116508, 11736.413179341169], "value": 9904.656071605847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.8106205662232, 1412.103314395359, 1429.8927580431466, 1433.5770947570466, 1434.146682723779, 7949.562774047425, 8352.874706879402, 8385.496870187793, 8386.119457456964, 8386.371022173338, 7296.817579560514, 7572.12920514141, 7594.43041771868, 7875.80310492238, 7986.840263194733, 2207.4150350157183, 2249.134083377895, 2253.526759380524, 2253.5943530164564, 2254.4701995031155, 2175.347395781267, 2263.5020295534955, 2282.8972696228625, 2288.21056735426, 2288.622377307742, 2002.3392280547384, 2090.3519908828234, 2092.487340601555, 2204.7574766775642, 2294.9655755163685, 2262.3891549281766, 2291.873946932935, 2292.449812045114, 2292.5414917016665, 2293.3693234360517, 2228.3983324502456, 2306.6770652108717, 2311.884405779704, 2316.3861576682193, 2319.9188028418926, 7093.467989900726, 8142.43002989791, 8225.629846656915, 8229.753107406787, 8273.296769774577, 4996.575291100261, 5149.407818100929, 5158.303629010074, 5162.199859021857, 5164.406093299281, 3213.935721285561, 3275.1157187559465, 3309.635940046593, 3482.2165012872156, 3485.9651403485896, 1187.5012494752225, 1194.8386967759327, 1216.6958260434892, 1229.3668760588305, 1246.9875301246961, 10491.0, 10567.524461399256, 10575.524101415454, 10613.363198208132, 10616.469176541143, 4041.777702226347, 4337.327714204303, 4532.478764942008, 4579.977100114478, 4580.702254353457, 9332.90667093334, 9344.486053267136, 9353.672621458216, 9353.766155846022, 9367.344285899922, 5190.455002224712, 5193.221016847428, 5195.766190521435, 5195.9740201298755, 5196.896062078813, 4658.627309815207, 4680.063987202573, 4724.740139292372, 4725.692829966041, 4790.736509491941, 3724.571674257495, 3741.7380783344906, 3798.506194194737, 3823.044238940265, 3862.6523612874976, 1254.0, 1256.9120161588785, 1257.1639859493516, 1270.8093785931999, 1274.7323062157052, 709.7906117695293, 757.7158565537924, 764.9847003060019, 784.8077221080872, 842.2083241752733, 5273.156294992783, 5442.836714898559, 5513.5037846594, 5728.05487094633, 5729.828105156852, 977.7848873247874, 978.5107446276861, 984.4585477987222, 1001.62439085343, 1028.7603437857365, 3180.7773455858332, 3445.242046749711, 3582.390993531106, 3684.8157592120824, 3750.2499500099543, 9577.23382129442, 9811.607535698635, 9953.701388958343, 9979.950100249598, 9983.35108217978, 9156.542172891328, 9571.61713531451, 9869.654562090293, 9905.257105716955, 9907.455089969977, 7456.51532650376, 7659.157492675801, 7661.233876612406, 7662.080550333995, 7668.27151420603, 5519.613627046068, 5519.751611177427, 5526.336839579276, 5750.7987220447085, 5854.882902342015, 9104.13510716481, 9208.0, 9217.262618990331, 9218.631254749735, 9235.07649235071, 7797.647011784987, 7996.880046799186, 9084.909810822479, 9305.255579553756, 9315.39449935752, 6322.557420980487, 6808.795736127924, 7094.11323584552, 7139.8572028560175, 7157.463190260855, 7798.181190974866, 7799.961000194962, 7887.723929662434, 7934.60326983637, 7989.680412783424, 6865.382115609619, 6961.756338528127, 6998.0, 7019.122609673791, 7107.82230444243, 6949.513534052568, 6954.269801670752, 6994.2306346302885, 7003.54476958996, 7011.1586609607175, 5441.319835020622, 6267.122602835516, 6284.685765711788, 6335.303116657256, 6364.236291645032, 4847.563719265283, 4866.197077482176, 4899.265110233494, 4939.703617782944, 5021.874453138701, 4688.601468875047, 4857.9028419432125, 4957.975210123926, 5050.469700681522, 5060.2156665716875], "value": 4335.137766157283, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.2255151424437, 361.681720086324, 369.6839202481878, 375.96428339307755, 377.0968530369766, 5296.205569164627, 5336.573074154068, 5353.0193828283545, 5356.919646205305, 5399.868076515611, 4766.952330476694, 4772.952270477294, 5117.232415137732, 5164.483551644834, 5302.844807339637, 3627.891163265106, 4100.2209580179815, 4175.394567787669, 4194.266003449399, 4204.747715137086, 3911.3741801311794, 3931.351327031051, 4137.97931010346, 4141.9438043298915, 4161.729487583298, 3984.980075099635, 4112.527059388164, 4126.546079931205, 4153.54311025787, 4188.350805625118, 4090.734102283342, 4110.486189226347, 4117.629413352813, 4150.688698347606, 4176.245976689783, 3650.5984341722387, 3669.486271921933, 3672.816359182057, 3679.9264014719824, 3684.0, 4915.950840491585, 5157.252198431244, 5213.9217911730975, 5217.0, 5229.764660590245, 4380.759058251795, 4547.3179023146795, 4812.711237325737, 4828.939680700698, 4834.066373450612, 4623.8150473981, 5348.8127915523155, 5605.355384130837, 5608.130739735348, 5633.633813802131, 4076.65470394772, 4092.626276759472, 4106.07613287011, 4109.137410833807, 4148.0, 5202.869928251824, 5388.7305634718105, 5434.347878254595, 5868.442497962772, 5872.034622515645, 3324.4096375913955, 3858.324793161242, 3893.7079719021185, 3907.159960608483, 3908.7068469864876, 3168.445522033635, 3878.0, 3965.4448377227213, 3971.324874771296, 3979.2041591681777, 4210.621044105984, 4427.80074896624, 4589.9213684784045, 4601.883133758509, 4626.629869610423, 4951.801927922915, 4991.401031876198, 5420.34955805298, 5623.943760562423, 5640.943590564042, 1121.9046381057537, 1156.7744289863397, 1235.5922545559943, 1275.953717951271, 1276.942537585811, 752.9472936894474, 819.8155415031689, 839.7522730794308, 839.9076101628816, 848.9812225329506, 4299.720518166309, 4717.386739723814, 4914.680545764514, 5003.974185292006, 5055.615769205784, 4761.738139393064, 5056.064628043824, 5225.16397376418, 5521.282233309725, 5547.861303467445, 4517.887052823706, 4829.203181475087, 4993.375828021497, 5240.895182096337, 5361.008213480519, 2510.0, 2638.9208323750318, 2861.2703760541344, 2878.5394336905993, 2878.7265209805037, 4754.95245047552, 5553.361363443175, 5563.972180139073, 5777.45114214149, 5791.8552036199435, 4541.682911955562, 4617.1300623247635, 5215.53060224574, 5480.6437581557075, 5769.88460230793, 5193.935243275044, 5424.907802552586, 5437.4562543745305, 5628.465295796893, 5737.770489180386, 4824.975875120602, 5154.922401434659, 5164.586833053271, 5185.844424667266, 5362.0668984535105, 4362.709577471853, 4808.543188397097, 5289.233061206027, 5310.761015754378, 5471.343438787371, 5297.390800058042, 5444.455554444524, 5494.450554944474, 5708.315002199763, 5727.825795711786, 3179.586653735, 3537.327907697529, 3655.9817200913826, 3656.433252845813, 3666.651668091527, 5673.262475878109, 5753.482186603226, 5786.016377215799, 5796.797217057491, 5797.101449275369, 4747.406574178228, 4756.500567440369, 5215.81744638951, 5407.297051383295, 5593.965116453469, 4903.239997800347, 5364.7049412282695, 5608.7195640217005, 5617.606767526234, 5641.717914104361, 5250.527452529291, 5395.757190926496, 5475.397706252389, 5491.670499770026, 5542.889142217214, 4862.756862156949, 4937.259411088366, 5087.92368114486, 5528.612997090165, 5585.916211256753, 5173.560247378864, 5423.945760542502, 5433.076377015996, 5441.646292990943, 5458.454154584669], "value": 4040.390516110269, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.3555147040458, 727.7998550398639, 755.440973679477, 822.7572866004529, 833.1793183714043, 14931.850681493182, 15634.061956282616, 15640.84359156408, 15651.217439128055, 15671.921640391782, 14268.785968210459, 14350.569482915529, 14503.347349369278, 14599.708005839877, 14689.485867994623, 840.508302642953, 869.9391042627019, 874.4796845876725, 875.2822685397977, 877.5173654490037, 871.877937088808, 873.7247766953421, 874.5408660453281, 874.8818909447207, 875.5578432891381, 721.2282857342645, 750.692216191359, 758.9506682065676, 865.2083343740501, 885.2829208341265, 908.3913777768911, 910.2899738204211, 912.6577533424966, 914.9176574108362, 919.2094798473374, 685.3112621815044, 685.7120009595932, 687.2062767503542, 689.4243306838796, 690.823839920822, 12062.577809776616, 12388.81416778749, 14836.925815370962, 14880.181590012546, 15355.4625588105, 13638.0, 14125.78811317831, 14205.573832785032, 14350.425982960669, 14400.207988560625, 11872.821907671392, 11905.940470297679, 11981.580644677395, 12096.697582560506, 12226.877731222663, 3685.8157092145284, 3772.4152756322815, 3779.239656541731, 3846.673032792216, 3854.3640299350855, 105200.0, 108740.91259087386, 111736.0, 112547.0, 113120.0, 10659.520321585547, 11050.392228427514, 11136.276142050741, 11154.553817847196, 11312.0, 21881.890590547264, 22364.99357528915, 22655.546889062127, 22660.980255888524, 22674.546509069725, 7567.772966811004, 7635.618219089134, 7645.694172233048, 7718.691252349955, 7824.139344672081, 4149.97925010373, 4246.702730808814, 4300.720453170534, 4378.824847006148, 4398.494173170063, 3497.213127046394, 3604.513390692223, 3609.1698909251018, 3613.0425437259146, 3623.0217841182734, 591.0, 635.0788863687291, 654.7839213059679, 656.3666062249952, 661.9139511863427, 645.5868244323636, 657.5495785386929, 691.5746815708303, 695.8086526205334, 710.2684235237714, 9457.881319293043, 9891.901080989242, 10097.141742951932, 10124.54439550222, 10569.524371403306, 662.559398000334, 681.3356976947437, 695.5409429776424, 704.6969802984765, 708.2174197511779, 4885.285595205011, 4993.850184494471, 5002.024605202024, 5002.224655178453, 5004.099262132851, 108379.45810270899, 108766.45616772024, 109405.0, 109863.0, 112365.43817280862, 81419.5929020363, 87772.56113719518, 95780.15212241806, 97805.0, 100221.0, 81628.77556836652, 83803.0, 85277.0, 85807.99999999875, 87008.12991870126, 9588.76028099303, 9597.568109434953, 9609.56756945953, 9648.372855764354, 9734.707958760966, 46556.0, 51274.743626283125, 51296.74351628218, 51382.66012709162, 51711.74144129255, 66449.66679752796, 66709.33290667032, 67879.66060169865, 68593.65703171452, 69274.65362673154, 11651.359175245103, 12301.5951846481, 12333.260004399765, 12895.613131606067, 12909.160904541175, 14656.413743450568, 14666.985817725226, 14859.702805943605, 14890.404383824958, 15582.220888955733, 13594.524191653245, 13667.794983075062, 13806.930965345511, 13811.585652430444, 13834.446622135001, 13686.726265474834, 13975.440982360999, 14078.577642670736, 14088.084274522123, 14264.286785660883, 13073.803892941803, 13501.0, 13519.391627376988, 14017.0, 14060.507882224074, 10996.340219586851, 11198.16013798952, 11363.829542557034, 11415.771684566429, 11542.653720388402, 7817.452778305692, 10916.781664366827, 10995.505202266077, 11053.557857685602, 11314.151438642299], "value": 7580.997387840148, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1475.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 120.0, 163.0, 170.0, 114.0, 115.0, 114.0, 114.0, 115.0], "value": 125.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 109.0, 108.0, 165.0, 141.0, 110.0, 108.0, 113.0, 158.0, 112.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 186.0, 185.0, 184.0, 182.0, 185.0, 183.0, 183.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 57.0, 88.0, 61.0, 57.0, 59.0, 74.0, 96.0, 80.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 86.0, 70.0, 69.0, 70.0, 85.0, 69.0, 69.0, 82.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 86.0, 87.0, 87.0, 88.0, 86.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 99.0, 94.0, 94.0, 93.0, 122.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 85.0, 88.0, 87.0, 92.0, 87.0, 86.0, 85.0, 87.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 61.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 72.0, 77.0, 70.0, 69.0, 69.0, 70.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 87.0, 84.0, 88.0, 107.0, 105.0, 83.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 146.0, 145.0, 139.0, 138.0, 140.0, 139.0, 141.0, 242.0, 154.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 209.0, 175.0, 176.0, 170.0, 173.0, 176.0, 174.0, 173.0, 175.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 60.0, 69.0, 81.0, 86.0, 84.0, 87.0, 66.0], "value": 73.5, "unit": "score"}]}]} 09:38:31 INFO - Return code: 0 09:38:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:31 ERROR - # TBPL SUCCESS # 09:38:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:31 INFO - 2017-01-31 09:38:31.121 screenresolution[1661:9804] starting screenresolution argv=screenresolution get 09:38:31 INFO - 2017-01-31 09:38:31.124 screenresolution[1661:9804] Display 0: 1600x1200x32@60 09:38:31 INFO - 2017-01-31 09:38:31.130 screenresolution[1662:9807] starting screenresolution argv=screenresolution list 09:38:31 INFO - Available Modes on Display 0 09:38:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:31 INFO - Intel Iris: 09:38:31 INFO - Chipset Model: Intel Iris 09:38:31 INFO - Type: GPU 09:38:31 INFO - Bus: Built-In 09:38:31 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:31 INFO - Vendor: Intel (0x8086) 09:38:31 INFO - Device ID: 0x0a2e 09:38:31 INFO - Revision ID: 0x0009 09:38:31 INFO - Displays: 09:38:31 INFO - Display: 09:38:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:31 INFO - Display Serial Number: 1600x1200 60 09:38:31 INFO - Main Display: Yes 09:38:31 INFO - Mirror: Off 09:38:31 INFO - Online: Yes 09:38:31 INFO - Rotation: Supported 09:38:31 INFO - Return code: 0 09:38:31 INFO - Running post-action listener: _resource_record_post_action 09:38:31 INFO - [mozharness: 2017-01-31 17:38:31.370414Z] Finished run-tests step (success) 09:38:31 INFO - Running post-run listener: _resource_record_post_run 09:38:31 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37909504; Write bytes: 552674304; Read time: 242; Write time: 1658 09:38:31 INFO - TinderboxPrint: CPU usage
54.0% 09:38:31 INFO - TinderboxPrint: I/O read bytes / time
37,909,504 / 242 09:38:31 INFO - TinderboxPrint: I/O write bytes / time
552,674,304 / 1,658 09:38:31 INFO - TinderboxPrint: CPU idle
2,210.9 (46.0%) 09:38:31 INFO - TinderboxPrint: CPU system
965.9 (20.1%) 09:38:31 INFO - TinderboxPrint: CPU user
1,632.9 (33.9%) 09:38:31 INFO - TinderboxPrint: Swap in / out
289,931,264 / 0 09:38:31 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246614528; Write bytes: 238911488; Read time: 17919; Write time: 506 09:38:31 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35849216; Write bytes: 297418240; Read time: 224; Write time: 1107 09:38:31 INFO - Running post-run listener: _upload_blobber_files 09:38:31 INFO - Blob upload gear active. 09:38:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:38:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:32 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:38:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:38:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:33 INFO - (blobuploader) - INFO - Done attempting. 09:38:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:38:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:34 INFO - (blobuploader) - INFO - Done attempting. 09:38:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:38:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:35 INFO - (blobuploader) - INFO - Done attempting. 09:38:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:38:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:38:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:38 INFO - (blobuploader) - INFO - Done attempting. 09:38:38 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:38 INFO - Return code: 0 09:38:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/905bb0ebcecd85025ffc6b2dbfaa1121a3b9fa478411fcc7b4b5eda25388426c450fef7326fb187e03b12fba5f9f2a33950b7b49662ae2b762702a0e13d5f211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97c06b53a95512cd31f63fe31c8a75dc314316e420f0cf7f167ea1fec369649b0308c717e6bb522117a73359ed828dd140e5b487cd1f886e4a13e7895a18d142", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859b52acaf29d39b7b12be3f7cc9b1e04fb47d23c08397db1ba1871db24ae747e96b5243ff0985c60c003727221913c4e1be10a23a4cbd6804481f8897f4b377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84bfc29243db87be05d3c0d12a1077461b9b4b8f21bd92c27679a227c3facd10f0ee05c280df6f4410f493c972eac884069ab6951e293de32facb14bac5ebee"} 09:38:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:38 INFO - Contents: 09:38:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/905bb0ebcecd85025ffc6b2dbfaa1121a3b9fa478411fcc7b4b5eda25388426c450fef7326fb187e03b12fba5f9f2a33950b7b49662ae2b762702a0e13d5f211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97c06b53a95512cd31f63fe31c8a75dc314316e420f0cf7f167ea1fec369649b0308c717e6bb522117a73359ed828dd140e5b487cd1f886e4a13e7895a18d142", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859b52acaf29d39b7b12be3f7cc9b1e04fb47d23c08397db1ba1871db24ae747e96b5243ff0985c60c003727221913c4e1be10a23a4cbd6804481f8897f4b377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84bfc29243db87be05d3c0d12a1077461b9b4b8f21bd92c27679a227c3facd10f0ee05c280df6f4410f493c972eac884069ab6951e293de32facb14bac5ebee"} 09:38:38 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:38 INFO - Copying logs to upload dir... 09:38:38 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.684909 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-31 09:38:38.811487) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 09:38:38.815288) ========= 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.afzfu26INP/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.gjBPb3KDnO/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/905bb0ebcecd85025ffc6b2dbfaa1121a3b9fa478411fcc7b4b5eda25388426c450fef7326fb187e03b12fba5f9f2a33950b7b49662ae2b762702a0e13d5f211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97c06b53a95512cd31f63fe31c8a75dc314316e420f0cf7f167ea1fec369649b0308c717e6bb522117a73359ed828dd140e5b487cd1f886e4a13e7895a18d142", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859b52acaf29d39b7b12be3f7cc9b1e04fb47d23c08397db1ba1871db24ae747e96b5243ff0985c60c003727221913c4e1be10a23a4cbd6804481f8897f4b377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84bfc29243db87be05d3c0d12a1077461b9b4b8f21bd92c27679a227c3facd10f0ee05c280df6f4410f493c972eac884069ab6951e293de32facb14bac5ebee"} build_url:https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/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/905bb0ebcecd85025ffc6b2dbfaa1121a3b9fa478411fcc7b4b5eda25388426c450fef7326fb187e03b12fba5f9f2a33950b7b49662ae2b762702a0e13d5f211", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97c06b53a95512cd31f63fe31c8a75dc314316e420f0cf7f167ea1fec369649b0308c717e6bb522117a73359ed828dd140e5b487cd1f886e4a13e7895a18d142", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/859b52acaf29d39b7b12be3f7cc9b1e04fb47d23c08397db1ba1871db24ae747e96b5243ff0985c60c003727221913c4e1be10a23a4cbd6804481f8897f4b377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d84bfc29243db87be05d3c0d12a1077461b9b4b8f21bd92c27679a227c3facd10f0ee05c280df6f4410f493c972eac884069ab6951e293de32facb14bac5ebee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 09:38:38.849606) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:38:38.849939) ========= 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.afzfu26INP/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.gjBPb3KDnO/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005023 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:38:38.898427) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 09:38:38.898726) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 09:38:38.899035) ========= ========= Total master_lag: 0.18 =========