builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0369 starttime: 1488126652.17 results: success (0) buildid: 20170226072009 builduid: 75b98f18a2dd456ebd3ce72b29488353 revision: c75ce3362c05d005c4b00d69de9b2ad177b648ed ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.168022) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.168452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.168722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.193763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.194082) ========= 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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-26 08:30:52-- 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% 147M=0s 2017-02-26 08:30:52 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.563957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.781516) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.781844) ========= 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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.021290 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:52.830685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-26 08:30:52.831058) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c75ce3362c05d005c4b00d69de9b2ad177b648ed --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 c75ce3362c05d005c4b00d69de9b2ad177b648ed --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.feZ0ka7vSc/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.WoPtRQMZVR/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-26 08:30:52,919 truncating revision to first 12 chars 2017-02-26 08:30:52,919 Setting DEBUG logging. 2017-02-26 08:30:52,919 attempt 1/10 2017-02-26 08:30:52,919 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c75ce3362c05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-26 08:30:54,430 unpacking tar archive at: mozilla-central-c75ce3362c05/testing/mozharness/ program finished with exit code 0 elapsedTime=1.775976 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-26 08:30:54.630606) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:54.630920) ========= script_repo_revision: c75ce3362c05d005c4b00d69de9b2ad177b648ed ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:54.631293) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:54.631553) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-26 08:30:54.651487) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-02-26 08:30:54.651753) ========= /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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:30:54 INFO - MultiFileLogger online at 20170226 08:30:54 in /builds/slave/test 08:30:54 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 08:30:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:54 INFO - {'append_to_log': False, 08:30:54 INFO - 'base_work_dir': '/builds/slave/test', 08:30:54 INFO - 'blob_upload_branch': 'Firefox', 08:30:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:54 INFO - 'branch': 'Firefox', 08:30:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:54 INFO - 'config_files': ('talos/mac_config.py',), 08:30:54 INFO - 'default_actions': ('clobber', 08:30:54 INFO - 'read-buildbot-config', 08:30:54 INFO - 'download-and-extract', 08:30:54 INFO - 'populate-webroot', 08:30:54 INFO - 'create-virtualenv', 08:30:54 INFO - 'install', 08:30:54 INFO - 'run-tests'), 08:30:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:54 INFO - 'download_minidump_stackwalk': True, 08:30:54 INFO - 'download_symbols': 'ondemand', 08:30:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:54 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:54 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:54 INFO - 'gecko_profile': False, 08:30:54 INFO - 'gecko_profile_interval': 0, 08:30:54 INFO - 'installer_path': 'installer.exe', 08:30:54 INFO - 'log_level': 'info', 08:30:54 INFO - 'log_name': 'talos', 08:30:54 INFO - 'log_to_console': True, 08:30:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:54 INFO - 'opt_config_files': (), 08:30:54 INFO - 'pip_index': False, 08:30:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:54 INFO - 'cmd': ('bash', 08:30:54 INFO - '-c', 08:30:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:54 INFO - 'enabled': True, 08:30:54 INFO - 'halt_on_failure': False, 08:30:54 INFO - 'name': 'check_screen_resolution'},), 08:30:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:54 INFO - 'cmd': ('bash', 08:30:54 INFO - '-c', 08:30:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:54 INFO - 'enabled': True, 08:30:54 INFO - 'halt_on_failure': False, 08:30:54 INFO - 'name': 'check_screen_resolution'},), 08:30:54 INFO - 'run_cmd_checks_enabled': True, 08:30:54 INFO - 'suite': 'dromaeojs', 08:30:54 INFO - 'system_bits': '32', 08:30:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:54 INFO - 'title': 't-yosemite-r7-0369', 08:30:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:54 INFO - 'use_talos_json': True, 08:30:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:54 INFO - 'work_dir': 'build'} 08:30:54 INFO - [mozharness: 2017-02-26 16:30:54.859863Z] Running clobber step. 08:30:54 INFO - Running pre-action listener: _resource_record_pre_action 08:30:54 INFO - Running main action method: clobber 08:30:54 INFO - rmtree: /builds/slave/test/build 08:30:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:57 INFO - Running post-action listener: _resource_record_post_action 08:30:57 INFO - [mozharness: 2017-02-26 16:30:57.076816Z] Finished clobber step (success) 08:30:57 INFO - [mozharness: 2017-02-26 16:30:57.076990Z] Running read-buildbot-config step. 08:30:57 INFO - Running pre-action listener: _resource_record_pre_action 08:30:57 INFO - Running main action method: read_buildbot_config 08:30:57 INFO - Using buildbot properties: 08:30:57 INFO - { 08:30:57 INFO - "project": "", 08:30:57 INFO - "product": "firefox", 08:30:57 INFO - "script_repo_revision": "production", 08:30:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:30:57 INFO - "repository": "", 08:30:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:30:57 INFO - "buildid": "20170226072009", 08:30:57 INFO - "pgo_build": "False", 08:30:57 INFO - "basedir": "/builds/slave/test", 08:30:57 INFO - "buildnumber": 57, 08:30:57 INFO - "slavename": "t-yosemite-r7-0369", 08:30:57 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:30:57 INFO - "platform": "yosemite_r7", 08:30:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:30:57 INFO - "branch": "mozilla-central", 08:30:57 INFO - "repo_path": "mozilla-central", 08:30:57 INFO - "revision": "c75ce3362c05d005c4b00d69de9b2ad177b648ed", 08:30:57 INFO - "stage_platform": "macosx64", 08:30:57 INFO - "builduid": "75b98f18a2dd456ebd3ce72b29488353", 08:30:57 INFO - "slavebuilddir": "test" 08:30:57 INFO - } 08:30:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:30:57 INFO - Running post-action listener: _resource_record_post_action 08:30:57 INFO - [mozharness: 2017-02-26 16:30:57.082444Z] Finished read-buildbot-config step (success) 08:30:57 INFO - [mozharness: 2017-02-26 16:30:57.082673Z] Running download-and-extract step. 08:30:57 INFO - Running pre-action listener: _resource_record_pre_action 08:30:57 INFO - Running main action method: download_and_extract 08:30:57 INFO - mkdir: /builds/slave/test/build/tests 08:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:57 INFO - trying https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:30:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/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 08:30:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/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 08:30:57 INFO - Downloaded 1199 bytes. 08:30:57 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:30:57 INFO - Using the following test package requirements: 08:30:57 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:30:57 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:30:57 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:30:57 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'jsshell-mac.zip'], 08:30:57 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:30:57 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:30:57 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:30:57 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:30:57 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:30:57 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:30:57 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:30:57 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:30:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:30:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:30:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:30:59 INFO - Expected file size: 51745838 08:30:59 INFO - Obtained file size: 51745838 08:31:15 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 08:31:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:31:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:31:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:31:17 INFO - Expected file size: 51745838 08:31:17 INFO - Obtained file size: 51745838 08:31:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:31:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:31:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:31:33 INFO - Expected file size: 14404421 08:31:33 INFO - Obtained file size: 14404421 08:31:33 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')]} 08:31:33 INFO - trying https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:31:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:31:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/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 08:31:35 INFO - Downloaded 74910728 bytes. 08:31:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:31:35 INFO - mkdir: /builds/slave/test/properties 08:31:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:31:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:31:35 INFO - Contents: 08:31:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:31:36 INFO - Running post-action listener: _resource_record_post_action 08:31:36 INFO - Running post-action listener: set_extra_try_arguments 08:31:36 INFO - [mozharness: 2017-02-26 16:31:36.390118Z] Finished download-and-extract step (success) 08:31:36 INFO - [mozharness: 2017-02-26 16:31:36.390243Z] Running populate-webroot step. 08:31:36 INFO - Running pre-action listener: _resource_record_pre_action 08:31:36 INFO - Running main action method: populate_webroot 08:31:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:31:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:31:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:31:36 INFO - '--tppagecycles', 08:31:36 INFO - '7'], 08:31:36 INFO - 'tests': ['tsvgm']}}, 08:31:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:31:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:31:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:31:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:31:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['damp', 'tps']}, 08:31:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'tests': ['damp', 'tps']}, 08:31:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['dromaeo_dom']}, 08:31:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:31:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['basic_compositor_video']}, 08:31:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:31:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['a11yr', 08:31:36 INFO - 'ts_paint', 08:31:36 INFO - 'tpaint', 08:31:36 INFO - 'sessionrestore', 08:31:36 INFO - 'sessionrestore_no_auto_restore', 08:31:36 INFO - 'tabpaint']}, 08:31:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:31:36 INFO - 'ts_paint', 08:31:36 INFO - 'tpaint', 08:31:36 INFO - 'sessionrestore', 08:31:36 INFO - 'sessionrestore_no_auto_restore', 08:31:36 INFO - 'tabpaint']}, 08:31:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:31:36 INFO - 'ts_paint', 08:31:36 INFO - 'tpaint', 08:31:36 INFO - 'sessionrestore', 08:31:36 INFO - 'sessionrestore_no_auto_restore', 08:31:36 INFO - 'tabpaint']}, 08:31:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:31:36 INFO - 'ts_paint', 08:31:36 INFO - 'tpaint', 08:31:36 INFO - 'sessionrestore', 08:31:36 INFO - 'sessionrestore_no_auto_restore', 08:31:36 INFO - 'tabpaint']}, 08:31:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['a11yr', 08:31:36 INFO - 'ts_paint', 08:31:36 INFO - 'tpaint', 08:31:36 INFO - 'sessionrestore', 08:31:36 INFO - 'sessionrestore_no_auto_restore', 08:31:36 INFO - 'tabpaint']}, 08:31:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['a11yr', 08:31:36 INFO - 'ts_paint', 08:31:36 INFO - 'tpaint', 08:31:36 INFO - 'sessionrestore', 08:31:36 INFO - 'sessionrestore_no_auto_restore', 08:31:36 INFO - 'tabpaint']}, 08:31:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['tsvgx', 08:31:36 INFO - 'tsvgr_opacity', 08:31:36 INFO - 'tart', 08:31:36 INFO - 'tscrollx', 08:31:36 INFO - 'cart', 08:31:36 INFO - 'tsvg_static']}, 08:31:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:31:36 INFO - 'tsvgr_opacity', 08:31:36 INFO - 'tart', 08:31:36 INFO - 'tscrollx', 08:31:36 INFO - 'cart', 08:31:36 INFO - 'tsvg_static']}, 08:31:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'talos_options': ['--disable-e10s'], 08:31:36 INFO - 'tests': ['tp5o']}, 08:31:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:31:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'talos_options': ['--disable-e10s', 08:31:36 INFO - '--xperf_path', 08:31:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:36 INFO - 'tests': ['tp5n']}, 08:31:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:31:36 INFO - 'talos_options': ['--xperf_path', 08:31:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:36 INFO - 'tests': ['tp5n']}}, 08:31:36 INFO - 'talos.zip': {'path': '', 08:31:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:36 INFO - Running post-action listener: _resource_record_post_action 08:31:36 INFO - [mozharness: 2017-02-26 16:31:36.409177Z] Finished populate-webroot step (success) 08:31:36 INFO - [mozharness: 2017-02-26 16:31:36.409308Z] Running create-virtualenv step. 08:31:36 INFO - Running pre-action listener: _resource_record_pre_action 08:31:36 INFO - Running main action method: create_virtualenv 08:31:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:31:36 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 08:31:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:31:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:31:36 INFO - Using real prefix '/tools/python27' 08:31:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:31:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:31:40 INFO - Installing pip.................done. 08:31:40 INFO - Return code: 0 08:31:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:31:40 INFO - Reading from file tmpfile_stdout 08:31:40 INFO - Output received: 08:31:40 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) 08:31:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:31:40 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')]} 08:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 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')]} 08:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:40 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 08:31:40 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 08:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:40 INFO - 'HOME': '/Users/cltbld', 08:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:40 INFO - 'LOGNAME': 'cltbld', 08:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:40 INFO - 'MOZ_NO_REMOTE': '1', 08:31:40 INFO - 'NO_EM_RESTART': '1', 08:31:40 INFO - 'PAGER': '/bin/cat', 08:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:40 INFO - 'PWD': '/builds/slave/test', 08:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:40 INFO - 'SHELL': '/bin/bash', 08:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:40 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:40 INFO - 'USER': 'cltbld', 08:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:40 INFO - 'XPC_FLAGS': '0x0', 08:31:40 INFO - 'XPC_SERVICE_NAME': '0', 08:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:40 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 08:31:40 INFO - Cleaning up... 08:31:40 INFO - Return code: 0 08:31:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:31:40 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')]} 08:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 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')]} 08:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:40 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 08:31:40 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 08:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:40 INFO - 'HOME': '/Users/cltbld', 08:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:40 INFO - 'LOGNAME': 'cltbld', 08:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:40 INFO - 'MOZ_NO_REMOTE': '1', 08:31:40 INFO - 'NO_EM_RESTART': '1', 08:31:40 INFO - 'PAGER': '/bin/cat', 08:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:40 INFO - 'PWD': '/builds/slave/test', 08:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:40 INFO - 'SHELL': '/bin/bash', 08:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:40 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:40 INFO - 'USER': 'cltbld', 08:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:40 INFO - 'XPC_FLAGS': '0x0', 08:31:40 INFO - 'XPC_SERVICE_NAME': '0', 08:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:40 INFO - Downloading/unpacking psutil>=3.1.1 08:31:40 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 08:31:40 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 08:31:40 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 08:31:40 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 08:31:40 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 08:31:40 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 08:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:31:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:45 INFO - Installing collected packages: psutil 08:31:45 INFO - Running setup.py install for psutil 08:31:45 INFO - building 'psutil._psutil_osx' extension 08:31:45 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 08:31:45 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 08:31:45 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 08:31:45 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 08:31:45 INFO - building 'psutil._psutil_posix' extension 08:31:45 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 08:31:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:31:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:31:45 INFO - ^ 08:31:45 INFO - 1 warning generated. 08:31:45 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 08:31:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:45 INFO - Successfully installed psutil 08:31:45 INFO - Cleaning up... 08:31:45 INFO - Return code: 0 08:31:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:31:45 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')]} 08:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:45 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')]} 08:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:45 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:45 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 08:31:45 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 08:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:45 INFO - 'HOME': '/Users/cltbld', 08:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:45 INFO - 'LOGNAME': 'cltbld', 08:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:45 INFO - 'MOZ_NO_REMOTE': '1', 08:31:45 INFO - 'NO_EM_RESTART': '1', 08:31:45 INFO - 'PAGER': '/bin/cat', 08:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:45 INFO - 'PWD': '/builds/slave/test', 08:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:45 INFO - 'SHELL': '/bin/bash', 08:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:45 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:45 INFO - 'USER': 'cltbld', 08:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:45 INFO - 'XPC_FLAGS': '0x0', 08:31:45 INFO - 'XPC_SERVICE_NAME': '0', 08:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:31:46 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 08:31:46 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 08:31:46 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 08:31:46 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 08:31:46 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 08:31:46 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 08:31:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:31:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:31:48 INFO - Installing collected packages: mozsystemmonitor 08:31:48 INFO - Running setup.py install for mozsystemmonitor 08:31:48 INFO - Successfully installed mozsystemmonitor 08:31:48 INFO - Cleaning up... 08:31:49 INFO - Return code: 0 08:31:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:49 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:49 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 08:31:49 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 08:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:49 INFO - 'HOME': '/Users/cltbld', 08:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:49 INFO - 'LOGNAME': 'cltbld', 08:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:49 INFO - 'MOZ_NO_REMOTE': '1', 08:31:49 INFO - 'NO_EM_RESTART': '1', 08:31:49 INFO - 'PAGER': '/bin/cat', 08:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:49 INFO - 'PWD': '/builds/slave/test', 08:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:49 INFO - 'SHELL': '/bin/bash', 08:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:49 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:49 INFO - 'USER': 'cltbld', 08:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:49 INFO - 'XPC_FLAGS': '0x0', 08:31:49 INFO - 'XPC_SERVICE_NAME': '0', 08:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:49 INFO - Downloading/unpacking jsonschema==2.5.1 08:31:49 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 08:31:49 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 08:31:49 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 08:31:49 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 08:31:49 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 08:31:49 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 08:31:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:31:51 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 08:31:51 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 08:31:51 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 08:31:51 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 08:31:51 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 08:31:51 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 08:31:52 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:52 INFO - warning: no files found matching '*.txt' 08:31:52 INFO - no previously-included directories found matching 'build' 08:31:52 INFO - no previously-included directories found matching 'dist' 08:31:52 INFO - no previously-included directories found matching '.git*' 08:31:52 INFO - Installing collected packages: jsonschema, functools32 08:31:52 INFO - Running setup.py install for functools32 08:31:52 INFO - warning: no files found matching '*.txt' 08:31:52 INFO - no previously-included directories found matching 'build' 08:31:52 INFO - no previously-included directories found matching 'dist' 08:31:52 INFO - no previously-included directories found matching '.git*' 08:31:52 INFO - Successfully installed jsonschema functools32 08:31:52 INFO - Cleaning up... 08:31:52 INFO - Return code: 0 08:31:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:31:52 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')]} 08:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:52 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')]} 08:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:52 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:52 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 08:31:52 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 08:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:52 INFO - 'HOME': '/Users/cltbld', 08:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:52 INFO - 'LOGNAME': 'cltbld', 08:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:52 INFO - 'MOZ_NO_REMOTE': '1', 08:31:52 INFO - 'NO_EM_RESTART': '1', 08:31:52 INFO - 'PAGER': '/bin/cat', 08:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:52 INFO - 'PWD': '/builds/slave/test', 08:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:52 INFO - 'SHELL': '/bin/bash', 08:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:52 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:52 INFO - 'USER': 'cltbld', 08:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:52 INFO - 'XPC_FLAGS': '0x0', 08:31:52 INFO - 'XPC_SERVICE_NAME': '0', 08:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:31:53 INFO - Cleaning up... 08:31:53 INFO - Return code: 0 08:31:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:31:53 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')]} 08:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:53 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')]} 08:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:53 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:53 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 08:31:53 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 08:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:53 INFO - 'HOME': '/Users/cltbld', 08:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:53 INFO - 'LOGNAME': 'cltbld', 08:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:53 INFO - 'MOZ_NO_REMOTE': '1', 08:31:53 INFO - 'NO_EM_RESTART': '1', 08:31:53 INFO - 'PAGER': '/bin/cat', 08:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:53 INFO - 'PWD': '/builds/slave/test', 08:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:53 INFO - 'SHELL': '/bin/bash', 08:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:53 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:53 INFO - 'USER': 'cltbld', 08:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:53 INFO - 'XPC_FLAGS': '0x0', 08:31:53 INFO - 'XPC_SERVICE_NAME': '0', 08:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:53 INFO - Downloading/unpacking blobuploader==1.2.4 08:31:53 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 08:31:53 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 08:31:53 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 08:31:53 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 08:31:53 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 08:31:53 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 08:31:55 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - Downloading docopt-0.6.1.tar.gz 08:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:57 INFO - Running setup.py install for blobuploader 08:31:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:57 INFO - Running setup.py install for requests 08:31:57 INFO - Running setup.py install for docopt 08:31:58 INFO - Successfully installed blobuploader requests docopt 08:31:58 INFO - Cleaning up... 08:31:58 INFO - Return code: 0 08:31:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31: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')]} 08:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31: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')]} 08:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:58 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:31:58 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 08:31:58 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 08:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:58 INFO - 'HOME': '/Users/cltbld', 08:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:58 INFO - 'LOGNAME': 'cltbld', 08:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:58 INFO - 'MOZ_NO_REMOTE': '1', 08:31:58 INFO - 'NO_EM_RESTART': '1', 08:31:58 INFO - 'PAGER': '/bin/cat', 08:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:58 INFO - 'PWD': '/builds/slave/test', 08:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:58 INFO - 'SHELL': '/bin/bash', 08:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:31:58 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:58 INFO - 'USER': 'cltbld', 08:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:58 INFO - 'XPC_FLAGS': '0x0', 08:31:58 INFO - 'XPC_SERVICE_NAME': '0', 08:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:58 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-f_sbLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:58 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-314AGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:58 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ZUg2GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:58 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-v_VmPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:58 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-MNs2Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:58 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ytbop0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-tqLArz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-l4_lvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-8fwRKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-JuU617-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-x94JLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-OSaYa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-fLLx2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-0w70VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-722TMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-yIVA5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-BU4d0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:00 INFO - Running setup.py install for manifestparser 08:32:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:32:00 INFO - Running setup.py install for mozcrash 08:32:00 INFO - Running setup.py install for mozdebug 08:32:00 INFO - Running setup.py install for mozdevice 08:32:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:32:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:32:00 INFO - Running setup.py install for mozfile 08:32:00 INFO - Running setup.py install for mozhttpd 08:32:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Running setup.py install for mozinfo 08:32:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Running setup.py install for mozInstall 08:32:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Running setup.py install for mozleak 08:32:01 INFO - Running setup.py install for mozlog 08:32:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Running setup.py install for moznetwork 08:32:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:32:01 INFO - Running setup.py install for mozprocess 08:32:02 INFO - Running setup.py install for mozprofile 08:32:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:32:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:32:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:32:02 INFO - Running setup.py install for mozrunner 08:32:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:32:02 INFO - Running setup.py install for mozscreenshot 08:32:02 INFO - Running setup.py install for moztest 08:32:02 INFO - Running setup.py install for mozversion 08:32:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:32:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:02 INFO - Cleaning up... 08:32:02 INFO - Return code: 0 08:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:02 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')]} 08:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:02 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')]} 08:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:02 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:32:02 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 08:32:02 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 08:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:02 INFO - 'HOME': '/Users/cltbld', 08:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:02 INFO - 'LOGNAME': 'cltbld', 08:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:02 INFO - 'MOZ_NO_REMOTE': '1', 08:32:02 INFO - 'NO_EM_RESTART': '1', 08:32:02 INFO - 'PAGER': '/bin/cat', 08:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:02 INFO - 'PWD': '/builds/slave/test', 08:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:02 INFO - 'SHELL': '/bin/bash', 08:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:32:02 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:02 INFO - 'USER': 'cltbld', 08:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:02 INFO - 'XPC_FLAGS': '0x0', 08:32:02 INFO - 'XPC_SERVICE_NAME': '0', 08:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-VDIEM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-wFk6rW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-m2LpCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-f0xnCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-9ax7PK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-jDjMf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-MHU5TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Mv6QRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:32:03 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)) 08:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:32:03 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-UDIcF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Ni1HkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-umKBRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-4FwS4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-b3RUf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-5Cdxdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-e6fCnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-5Zx2Oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:32:04 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)) 08:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:32:04 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-KrXxRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:32:04 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)) 08:32:04 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)) 08:32:04 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)) 08:32:04 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)) 08:32:04 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)) 08:32:04 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)) 08:32:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:07 INFO - Downloading blessings-1.6.tar.gz 08:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:32:07 INFO - Installing collected packages: blessings 08:32:07 INFO - Running setup.py install for blessings 08:32:07 INFO - Successfully installed blessings 08:32:07 INFO - Cleaning up... 08:32:07 INFO - Return code: 0 08:32:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:08 INFO - Reading from file tmpfile_stdout 08:32:08 INFO - Current package versions: 08:32:08 INFO - blessings == 1.6 08:32:08 INFO - blobuploader == 1.2.4 08:32:08 INFO - docopt == 0.6.1 08:32:08 INFO - functools32 == 3.2.3-2 08:32:08 INFO - jsonschema == 2.5.1 08:32:08 INFO - manifestparser == 1.1 08:32:08 INFO - mozInstall == 1.12 08:32:08 INFO - mozcrash == 1.0 08:32:08 INFO - mozdebug == 0.1 08:32:08 INFO - mozdevice == 0.49 08:32:08 INFO - mozfile == 1.2 08:32:08 INFO - mozhttpd == 0.7 08:32:08 INFO - mozinfo == 0.9 08:32:08 INFO - mozleak == 0.1 08:32:08 INFO - mozlog == 3.4 08:32:08 INFO - moznetwork == 0.27 08:32:08 INFO - mozprocess == 0.25 08:32:08 INFO - mozprofile == 0.28 08:32:08 INFO - mozrunner == 6.13 08:32:08 INFO - mozscreenshot == 0.1 08:32:08 INFO - mozsystemmonitor == 0.3 08:32:08 INFO - moztest == 0.8 08:32:08 INFO - mozversion == 1.4 08:32:08 INFO - psutil == 3.1.1 08:32:08 INFO - requests == 1.2.3 08:32:08 INFO - wsgiref == 0.1.2 08:32:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:08 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 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:32:08 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 08:32:08 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 08:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:08 INFO - 'HOME': '/Users/cltbld', 08:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:08 INFO - 'LOGNAME': 'cltbld', 08:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:08 INFO - 'MOZ_NO_REMOTE': '1', 08:32:08 INFO - 'NO_EM_RESTART': '1', 08:32:08 INFO - 'PAGER': '/bin/cat', 08:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:08 INFO - 'PWD': '/builds/slave/test', 08:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:08 INFO - 'SHELL': '/bin/bash', 08:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:32:08 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:08 INFO - 'USER': 'cltbld', 08:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:08 INFO - 'XPC_FLAGS': '0x0', 08:32:08 INFO - 'XPC_SERVICE_NAME': '0', 08:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 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)) 08:32:08 INFO - Cleaning up... 08:32:08 INFO - Return code: 0 08:32:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:32:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bde7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdea768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe308cc8e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, '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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 08:32:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:08 INFO - 'HOME': '/Users/cltbld', 08:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:08 INFO - 'LOGNAME': 'cltbld', 08:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:08 INFO - 'MOZ_NO_REMOTE': '1', 08:32:08 INFO - 'NO_EM_RESTART': '1', 08:32:08 INFO - 'PAGER': '/bin/cat', 08:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:08 INFO - 'PWD': '/builds/slave/test', 08:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:08 INFO - 'SHELL': '/bin/bash', 08:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:32:08 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:08 INFO - 'USER': 'cltbld', 08:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:08 INFO - 'XPC_FLAGS': '0x0', 08:32:08 INFO - 'XPC_SERVICE_NAME': '0', 08:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:32:08 INFO - Cleaning up... 08:32:08 INFO - Return code: 0 08:32:08 INFO - Running post-action listener: _resource_record_post_action 08:32:08 INFO - Running post-action listener: _start_resource_monitoring 08:32:08 INFO - Starting resource monitoring. 08:32:08 INFO - [mozharness: 2017-02-26 16:32:08.806419Z] Finished create-virtualenv step (success) 08:32:08 INFO - [mozharness: 2017-02-26 16:32:08.807520Z] Running install step. 08:32:08 INFO - Running pre-action listener: _resource_record_pre_action 08:32:08 INFO - Running main action method: install 08:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:32:09 INFO - Reading from file tmpfile_stdout 08:32:09 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:32:09 INFO - Reading from file tmpfile_stdout 08:32:09 INFO - Output received: 08:32:09 INFO - Usage: mozinstall [options] installer 08:32:09 INFO - Options: 08:32:09 INFO - -h, --help show this help message and exit 08:32:09 INFO - -d DEST, --destination=DEST 08:32:09 INFO - Directory to install application into. [default: 08:32:09 INFO - "/builds/slave/test"] 08:32:09 INFO - --app=APP Application being installed. [default: firefox] 08:32:09 INFO - mkdir: /builds/slave/test/build/application 08:32:09 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'] 08:32:09 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 08:32:29 INFO - Reading from file tmpfile_stdout 08:32:29 INFO - Output received: 08:32:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:32:29 INFO - Running post-action listener: _resource_record_post_action 08:32:29 INFO - [mozharness: 2017-02-26 16:32:29.484800Z] Finished install step (success) 08:32:29 INFO - [mozharness: 2017-02-26 16:32:29.484930Z] Running run-tests step. 08:32:29 INFO - Running pre-action listener: _resource_record_pre_action 08:32:29 INFO - Running main action method: run_tests 08:32:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:32:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:32:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:32:29 INFO - 2017-02-26 08:32:29.497 screenresolution[2757:53479] starting screenresolution argv=screenresolution get 08:32:29 INFO - 2017-02-26 08:32:29.500 screenresolution[2757:53479] Display 0: 1600x1200x32@60 08:32:29 INFO - 2017-02-26 08:32:29.505 screenresolution[2758:53482] starting screenresolution argv=screenresolution list 08:32:29 INFO - Available Modes on Display 0 08:32:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:32:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:32:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:32:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:32:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:32:29 INFO - Intel Iris: 08:32:29 INFO - Chipset Model: Intel Iris 08:32:29 INFO - Type: GPU 08:32:29 INFO - Bus: Built-In 08:32:29 INFO - VRAM (Dynamic, Max): 1536 MB 08:32:29 INFO - Vendor: Intel (0x8086) 08:32:29 INFO - Device ID: 0x0a2e 08:32:29 INFO - Revision ID: 0x0009 08:32:29 INFO - Displays: 08:32:29 INFO - Display: 08:32:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:32:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:32:29 INFO - Display Serial Number: 1600x1200 60 08:32:29 INFO - Main Display: Yes 08:32:29 INFO - Mirror: Off 08:32:29 INFO - Online: Yes 08:32:29 INFO - Rotation: Supported 08:32:29 INFO - Return code: 0 08:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:29 INFO - Python 2.7.3 08:32:29 INFO - Return code: 0 08:32:29 INFO - grabbing minidump binary from tooltool 08:32:29 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')]} 08:32:29 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 0x10bbc5d30>, '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 0x10baecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:29 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 08:32:29 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 08:32:29 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 08:32:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:29 INFO - Return code: 0 08:32:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:32:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:29 INFO - ENV: RUST_BACKTRACE is now 1 08:32:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:32:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:29 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/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 08:32:29 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/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0369 --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 08:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.feZ0ka7vSc/Render', 08:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:32:29 INFO - 'HOME': '/Users/cltbld', 08:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:32:29 INFO - 'LOGNAME': 'cltbld', 08:32:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:29 INFO - 'MOZ_NO_REMOTE': '1', 08:32:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:29 INFO - 'NO_EM_RESTART': '1', 08:32:29 INFO - 'PAGER': '/bin/cat', 08:32:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:29 INFO - 'PWD': '/builds/slave/test', 08:32:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:32:29 INFO - 'RUST_BACKTRACE': '1', 08:32:29 INFO - 'SHELL': '/bin/bash', 08:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WoPtRQMZVR/Listeners', 08:32:29 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 08:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:32:29 INFO - 'USER': 'cltbld', 08:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:29 INFO - 'XPC_FLAGS': '0x0', 08:32:29 INFO - 'XPC_SERVICE_NAME': '0', 08:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:32:29 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/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 08:32:30 INFO - mozversion application_buildid: 20170226072009 08:32:30 INFO - mozversion application_changeset: c75ce3362c05d005c4b00d69de9b2ad177b648ed 08:32:30 INFO - mozversion application_display_name: Nightly 08:32:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:30 INFO - mozversion application_name: Firefox 08:32:30 INFO - mozversion application_remotingname: firefox 08:32:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:32:30 INFO - mozversion application_vendor: Mozilla 08:32:30 INFO - mozversion application_version: 54.0a1 08:32:30 INFO - mozversion platform_buildid: 20170226072009 08:32:30 INFO - mozversion platform_changeset: c75ce3362c05d005c4b00d69de9b2ad177b648ed 08:32:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:32:30 INFO - mozversion platform_version: 54.0a1 08:32:30 INFO - using testdate: 1488126750 08:32:30 INFO - actual date: 1488126750 08:32:30 INFO - starting webserver on 'localhost:49493' 08:32:30 INFO - SUITE-START | Running 2 tests 08:32:30 INFO - TEST-START | dromaeo_css 08:32:30 INFO - operating with platform_type : mac_ 08:32:30 INFO - Initialising browser for dromaeo_css test... 08:32:30 INFO - TEST-INFO | started process 2768 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmptQK7lB/profile http://localhost:49493/getInfo.html) 08:32:32 INFO - PROCESS | 2768 | __metrics Screen width/height:1600/1200 08:32:32 INFO - PROCESS | 2768 | colorDepth:24 08:32:32 INFO - PROCESS | 2768 | Browser inner width/height: 1024/689 08:32:32 INFO - PROCESS | 2768 | __metrics 08:32:32 INFO - PROCESS | 2768 | 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" 08:32:32 INFO - PROCESS | 2768 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:32:33 INFO - TEST-INFO | 2768: exit 0 08:32:33 INFO - Browser initialized. 08:32:33 INFO - Running cycle 1/1 for dromaeo_css test... 08:32:33 INFO - TEST-INFO | started process 2770 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmptQK7lB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:45 INFO - PROCESS | 2770 | RSS: Main: 231079936 08:32:45 INFO - PROCESS | 2770 | 08:35:49 INFO - PROCESS | 2770 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49493/tests/dromaeo/cssquery-ext.html) 08:35:52 INFO - PROCESS | 2770 | RSS: Main: 474730496 08:35:52 INFO - PROCESS | 2770 | 08:37:35 INFO - PROCESS | 2770 | ************************* 08:37:35 INFO - PROCESS | 2770 | A coding exception was thrown and uncaught in a Task. 08:37:35 INFO - PROCESS | 2770 | 08:37:35 ERROR - PROCESS | 2770 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:37:35 INFO - PROCESS | 2770 | Full stack: 08:37:35 INFO - PROCESS | 2770 | ************************* 08:38:55 INFO - PROCESS | 2770 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-ext.html (next: http://localhost:49493/tests/dromaeo/cssquery-jquery.html) 08:38:56 INFO - PROCESS | 2770 | RSS: Main: 368820224 08:38:56 INFO - PROCESS | 2770 | 08:41:58 INFO - PROCESS | 2770 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49493/tests/dromaeo/cssquery-mootools.html) 08:41:59 INFO - PROCESS | 2770 | RSS: Main: 337526784 08:41:59 INFO - PROCESS | 2770 | 08:45:01 INFO - PROCESS | 2770 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49493/tests/dromaeo/cssquery-prototype.html) 08:45:02 INFO - PROCESS | 2770 | RSS: Main: 351780864 08:45:02 INFO - PROCESS | 2770 | 08:48:06 INFO - PROCESS | 2770 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49493/tests/dromaeo/cssquery-yui.html) 08:48:06 INFO - PROCESS | 2770 | RSS: Main: 335794176 08:48:06 INFO - PROCESS | 2770 | 08:51:09 INFO - PROCESS | 2770 | Cycle 1(1): loaded http://localhost:49493/tests/dromaeo/cssquery-yui.html (next: http://localhost:49493/tests/dromaeo/cssquery-dojo.html) 08:51:10 INFO - PROCESS | 2770 | RSS: Main: 578207744 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | __start_tp_report 08:51:10 INFO - PROCESS | 2770 | _x_x_mozilla_page_load 08:51:10 INFO - PROCESS | 2770 | _x_x_mozilla_page_load_details 08:51:10 INFO - PROCESS | 2770 | |i|pagename|runs| 08:51:10 INFO - PROCESS | 2770 | |0;dojo.html;2722.8366298022124;3146.9685303146975;3183.5702180205667;3199.296154845934;3221.5006673965545;5377.973110134453;5454.318210223722;5494.285742853428;5559.082751346028;5605.607607467474;4621.74580398078;5464.4262352453015;5502.669839809614;5531.363893152288;5591.077472217089;4981;5010.473900240468;5054.267131265964;5062;5069.543741063304;5144.6655967362185;5160.406553246387;5171.818986335479;5191.506806853362;5193.402758682762;4964.801407943678;4998.325226094467;5011.799528018875;5042.243663450475;5079.161938280179;5054.216596427523;5059.974700126512;5061.291419201315;5062.772175252123;5064.468230835783;5017.422996355393;5019.372578427697;5024.070546948789;5027.99440111979;5035.697858128524;5436.21174929637;5488.643238189505;5489.890202195974;5503.284573005483;5512.3385193777;5924.289085309791;5928.110783382484;5929.703514824241;5933.22868027158;5940.31686356066;6055.212822333113;6055.303640081403;6106.114613381079;6115.6330620162935;6144.815655530297;5609.1305847593685;5613.803516876889;5615;5621.7189140542805;5626.662400255997;6287.528435367366;6288.151099601495;6288.52836037299;6295.96852015737;6307.96846015767;5423.945760542423;5436.673799572039;5700.629459085146;5719.799807006735;5758.798442054508;5943.197668314722;5958.314793798769;6006.759729610767;6019.578629495893;6086.573939824259;4622.19111655459;5777.277840269966;5926;5929.762809487572;5948.643081415129;4959.2809042688605;5991.4907232884825;6002.909956350658;6009.519238461;6009.789657361971;4711.3639658646325;4751.619870410359;4765.785539650724;4782.473927867932;4785.95214047862;4248.702590818675;4255.212785634668;4300.225959327351;4339.717918335298;4370.541093185233;5462;5462.890742185214;5463.2078348639225;5464.644798088111;5487.451254874561;5872.295324560994;5938.346781853993;5960.254968128984;5972.223610930551;5979.491743201789;5427.48438898304;5489.752961116759;5491.588130890199;5501.862453438696;5506.807261745819;5181.3782346118705;5204.9219261711105;5253.711045892436;5260.815871444481;5261.237120617566;5645.294338207724;6229.1590635264565;6237.220347456568;6264.843378915563;6278.623282602967;6086.756529738761;6101.908471373022;6105.267367915879;6112.572119951561;6115.143879856734;6107.450329470369;6125.265974675844;6140;6273.686315684106;6290.9056364155485;5848.766049357978;5852.005159123045;5855.824325270249;5861.824145275648;5864.618799778001;5842.415758424124;5845.743165219498;5889.175515427929;5909.349971503045;5942.7920022799;5384.461553844584;5805.738741756547;5831.854203644943;5896.616719913192;5901.08533177341;5824;5829.912551311649;5838;5860.38465961085;5867.413258674099;4752.334673145693;5715.457031581993;5728.770849165987;5729.28383952006;5740.052891272893;5702.486776190163;5758.971205143947;5763.337216220189;5811.709414529172;5865.090910908816;5652.0674088774895;5667.603267771381;5675.375708672125;5679.659220446786;5683.403242659627 08:51:10 INFO - PROCESS | 2770 | |1;ext.html;755.1730854714087;778.4939789137062;831.56342919967;845.6194712379431;846.4202021615198;26186.34534136645;27265.863670681643;27369.45261094777;37402.064948376305;37710.622893771055;38277.23445531094;38467.807660961655;38482.230355392945;38482.653107141254;38504;8934.374593778455;9108.635654573809;9119.179273865351;9124.270058395346;9129.041450647654;7177.318154775288;7348;8969.461832290051;9091.499967501784;10663.093637040862;10724.14206863453;10876.619318323861;10886.673399797979;10889.965453281926;10925.399103049329;10801.945990270038;10854.077403420719;10862.782744345148;10867.83698244523;10868.6195983141;7128.429725621938;7277.345038946521;7296.343329100353;10577.36535807854;10642.414667193301;26439.867800661064;36352;36964.63035369639;37112.81443592792;38162;3515.8417871195857;3556.9110772230642;3561.269939662363;3572.696320812734;3637.6726094651344;22533;23290.3012909613;24902.1284255052;25226.873865630554;26088.739112608822;3005.9398812023855;3040.2095455181593;3103.301757104656;3131.9821058155962;3135.3885992231526;314184;314816;315591.4220428906;316437;316542.9999999977;43099.784501077294;43199.784001079795;43300.91747706269;43338.78330608327;43421.56578434238;36852.262954740756;37513.437298439996;37597.248055039294;53099;54166.72916635471;13082.215067096005;13209.867901321057;13290.800637990438;13293;13321.533746319026;11288.379139147426;11346.829797553044;11349.943250283695;11354.829677554842;11366.09071274296;12750.852423281949;12752.93623531895;12774.552890648783;12832.29422381778;12882.355882205853;5744.741486633112;5758.510526605201;5814.186013958049;5815.476607105381;5821.417858214229;1052.4369462337613;1074.231924174219;1083.772407794372;1097.9780404392027;1141.2068612314363;24762.25713228606;24837.967802634143;24849.751502485105;25028;25053.87473062623;1043.9247574997764;1106.6443606582034;1155.3760969076604;1163.3427113680912;1205.5412950330199;12348.444320005621;12776.55282065142;13051.869481305255;13085.607431777062;13097.803532947013;364334;410330;420263;421158;422190;50253.49746502561;50583.49416505861;50652.240216396785;50660.49339506632;50670.98658026819;1322.9603111906658;1384.605387464588;1391.5477469822551;1396.043710058613;1401.8387885393106;22553.43616409603;24573.754262457147;24713.382165446008;24906.626400603644;24950.12674556382;41582;41668.58331416647;41681.41504434449;41768;41771;372083;372207;372526.1373693114;372976.1351193227;373636;25891;31317;32236.197484452085;35961.1009724759;36684.633153668125;37790.811045944596;43515;43528;44389.11221775611;44545;31597.684023159472;34288.314233714686;34657.13357166091;36080;37027;35109.946701598994;35225.64774352326;35240.295194096485;36447.81776091103;36462.27075458423;35645.93062208138;36397.63602364049;36463.81768091142;36483.27033459369;36546.90359289225;29960.400791983604;31485.842570787914;31574.842125790143;31630.841845790623;31688.207794805316;19184.808151918303;23386.76613233846;23656.053757849495;23659.71027501461;23925.880370598035 08:51:10 INFO - PROCESS | 2770 | |2;jquery.html;335.44316434718365;354.5090050280361;378.33602028440083;396.2550405238152;416.6625033722685;12986.74026519469;13741.725165496684;20142;20453.284135055277;21045.05297261623;12087.033037357012;12357;18716.719249211274;21613.67579486305;21925.79408132552;5261.1319132343115;5427.158790387486;5495.972520137414;5563.721813909308;5567.025770490169;3599.6040435552068;3798.5061941947506;3803.8478460861534;5463.617546771728;5574.60977731559;3808.5048943637426;3830.080780612661;5846.941530584682;6131.141640170357;6160.476359509447;5273.446288139748;5980.641161530322;6140.631562106288;6170.907436388457;6217.440430361244;6037.63774173551;6072.2713274406915;6077.483413909823;6085.786997455068;6089.269287685462;17215;22477.988490517964;23079.53840923189;24951.376215594573;25471.61792573113;2318.258157389636;2515.8490490570625;2530.734272901356;2616.2674451153707;2655.8273712208643;10000.59997600095;10009;10298.330608510423;10415.479226038668;10461.581536738522;1473.6389584551853;1794.1836464408793;1805.8194180581968;1825.8813177143443;1828.0859570214893;118905;124525.75474245232;124572.75427245702;125047.3747631256;125511;11254.831177532344;11646.88353116458;11665.941670291595;11692.064634829194;11944.701382465508;12479.688007799878;13336.133151345131;13998.160110393206;13998.790018149735;14534.418623254951;7065.92934070663;8328.125546817497;8493.150684931581;8608.354373422018;8653.870191947126;6667.533272670964;8132.9186708132165;8142.226488483585;8144.389170812095;8327.250547450758;4687.359424201502;4774.570288673966;4785.473597904228;4877.3903262092235;4884.3650325457465;1709.9996502046442;1738.5479775258523;1951.897178094372;2026.409268724074;2032.2785411179113;998.565623953568;1017.1405162637559;1033.9896601034043;1036.7770929250248;1071.5231721883688;21195.68206476904;22049.00779464928;22133.66799498009;22240.666390004164;22263;952.9952350238204;974.7368210583103;982.3712823792779;1082.2802836113897;1093.2292733622776;10850.182341412681;11303.378314192634;11473.385883446688;11494.367809770376;11520.827187592193;682709.5864520646;684604;686788;700199;700598;130044;136095;137988.82122966467;138296.02151720412;140373;990.5294984882123;995.3430735714394;1014.4724743133677;1016.755978565139;1017.6946915925192;15187.481251874724;16373;17866.642667146843;18956;19279.22883084661;55045.174322384395;55848.441515585946;56173.71913140408;56989;57407;79879.60060199662;80374.19625803667;82902;83754;84342.57828710818;8302.335813135056;8379.413441059067;8617.95691021541;8651.091635378194;8671;9447.952760236154;10873.891261087289;12554.49782008746;12568.183068100545;13441;11201.775964480828;11319;11354.886451135382;11645.47595358194;11669.299842009506;11025.83461248098;11196.552137914392;11226.607068752555;11496.31022138641;11600.18798684084;11273.323600583992;11461.656150315504;11697.23967942081;11837.704057398634;11858.881411185777;8412.644459551335;8496.617652205541;8559.315254779727;8581.871271931052;8595;8747.475151490931;11085;11202.49588768491;11458.19792614509;11520.020798232244 08:51:10 INFO - PROCESS | 2770 | |3;mootools.html;1337.8795908368245;1390.4368730664078;1457.3004957620342;1459.2484870291794;1461.9853801461988;7967.84064318714;8395.950506186726;8396.538190399526;8405.789855253613;8410.369222308329;7967;8195.098539160688;8278;8405.705800296992;8409.327253819694;2235.7876001779805;2281.327008532487;2283.828712846535;2284.326123793485;2287.7025986621716;1947.51312171957;2120.34269376493;2164.8809315487642;2182.8144607708364;2182.9345119646355;1981.0986001369415;2012.3158126237076;2198.5492973940386;2269.8524595901295;2271.9091236350528;2234.575430668176;2235.966460503094;2236.0161528927383;2239.876806775635;2245.8091062259564;2187.6062308784412;2281.5208806150727;2285.4857657027037;2285.5885940530698;2290.404494831339;7708.614569271514;8344.040435349953;8437.451565648213;8442.19799119089;8444.282236009947;4750.358701575277;5161.251618515332;5162.561182299509;5163.612729045337;5165.251038599382;2831.419558990402;2877.827330360185;3261.1521004538754;3413.112590726404;3461.6192218855904;1148.5405837664916;1185.549491193341;1199.1366216324232;1230.8276841242234;1248.4257241668843;10457.26799124054;10607.363558186533;10702.143828493701;10719.196060295431;10778.730531736612;4193.35003074524;4332.415123958225;4344.435223420998;4424.60178583929;4444.933326000112;7124.287571242834;8544.2310192083;8571.314294856396;8619.870701939475;8642.135786421308;4979.228219625964;4984.975075124601;4985.351904252424;4985.825496107619;5002.424721157041;4502.842400515966;4598.632109431237;4651.511591282867;4686.859394218179;4688.249880019181;3508.298340331944;3557.616397014007;3575.76757510761;3603.837827297778;3640.635936406391;1125.870524889648;1126.983095253572;1132.6941725734005;1144.6966553863233;1144.7882141803793;571.5027925704663;639.9760383386637;683.928187540311;753.6269546574481;785.0853755375039;5480.698561579152;5527.944720552823;5559.054960656698;5570.693611851387;5648.576356773258;534.3134072716541;566.5637459156427;569.7236840132532;582.2605291280136;672.3343889549311;3633.9636603634153;3652.726045546595;3757.7369584129397;3825.6365645263654;3858.575556688762;8988.910110898938;9311.301652376098;9614.134727874525;9730.805383892282;9825.213982881352;9856.359336643096;9868.865080515689;9872;9881.555330010167;9882.11061004513;7350.742724004741;7581.696732130653;7638.732644357421;7692.307692307607;7710.7686769397005;5610.13042978339;5622.522085622604;5717.828465146053;5760;5827.737751801094;8613.612387442456;8800.295976321751;9188.219001384958;9202.815943680955;9210.032946540518;8799.824003519765;9269.814603707753;9271.165595096474;9369.484678342757;9372.297077719062;6981.162260528566;7098.219195888551;7107.50247482692;7117.110361204849;7118.074650295428;7604.8479030417975;7655.6937722490475;7660.846783064419;7670.232976702285;7676.769696909102;5715.714214289352;6838.384545390939;6950.721971121301;6986.685599147949;7038.507304488637;6735.124433823572;6818.727250910107;6821.727130914708;6884.793456196322;6891.517593768589;5251.343582052244;6366.808995730135;6368.299487056327;6379.74481020754;6380.680965951591;4108.77401742907;4869.31829543857;4976.477469865612;5036.395632524121;5059.165237735732;4458.598726114666;4854.393200849894;4901.50984901507;5166.4316925137455;5177.922331165111 08:51:10 INFO - PROCESS | 2770 | |4;prototype.html;290.6904147083356;294.3538932044164;360.6988164882323;360.90616439725665;362.70439591732753;4514.480834704002;4542.977829988251;5073.615400761641;5147.485251474851;5234.560495863635;4211.726237794549;4559.612432943196;4670.90658186836;5108.4636113208135;5118.48815118488;3532.717382609398;3534.469829525579;4057.249408859363;4069.6540794032544;4089.9182016359655;3819.503464549605;4065.1869626074754;4071.819172439989;4079.1637714268504;4084.1627466369323;3341.6274445143977;3970.58308877568;3983.103801644636;3984.900377490557;4023.6177563131455;3918.314294998364;4004.2191772604356;4004.7797371144575;4015.7992100394863;4044.57531959143;3588.2823435313044;3601.441776524643;3611.855525778966;3629.691476224524;3636.636336366342;3996.4604778355133;4218.4403252941665;5111.463296353866;5120.206368012964;5128.1282182029145;3733.925321493582;3935.6457918787446;4459.585010839278;4480.372747815309;4482.574155455227;4487.865364039084;4920.950790492121;5332.880095180017;5467.289252397163;5492.231087647732;3126.718595326409;3599.4600809878466;3666.871659491905;3719.6280371962857;3725.62743725628;5412.648177868426;5522.834314970557;5687.886242275132;5700.660344818964;5722.027255366597;3239.61124665037;3500.492428597839;3643.854245830137;3648.325059863934;3668.724845636588;3661;3717.925641487155;3817.1602247505502;3822.827972741233;3844.442555829389;4410.29435290352;4427.358033085249;4427.955720442818;4433.7831084457775;4484.461864576207;5212.582993360522;5393.78424863009;5395.8938417624295;5425.674459532441;5570.888582228333;1072.522727386321;1234.6788935838567;1244.234795600699;1248.3071895098308;1262.2868079535217;768.3238749900164;788.8128366807917;841.5834162089805;843.0431460292612;862.0732712334266;4041.818118184689;4579.290210017452;4822.397200349957;4834.661573689878;4843.854684359475;4842.394700662418;5328.547073498796;5359.936424476527;5364.8122315718765;5378.489043540935;4135;4154.584541545882;4237.555056719062;4635.860924172281;4854.004928989549;2251.8085962693353;2447.49826285611;2469.839175587471;2718.075854209578;2758.475889580973;4717.339572459859;5020.849374518771;5546.619778814544;5586.385497595261;5623.465770751772;4843.716415149988;5165.992631436834;5512.779488820483;5514.36584792754;5540.556755459554;4590.724556526619;5515.062439385234;5559.969022458766;5606.327240731139;5624.200255918149;4541.704789188692;4620.607248383925;5300.522952934254;5409.83770486886;5411.242426060433;4815.783289751899;5097.643164978416;5129.101882569349;5258.763355648928;5281.445448227881;4953.2570114483115;5193.324867767166;5298.675331167208;5605.915911261252;5653.547716182778;3439.466882633196;3586.6054733979763;3729.291434627412;3757.981210094041;3867.7099217559357;4642.259152817694;4833.10587541306;5001.24981252815;5547.7780888764;5580.218769372373;4845.370101886732;5121.8975620486635;5266.762995665128;5405.216278627965;5413.998410294108;4433.911321773611;5308.628396012242;5395.622306438511;5446.972765136149;5573.6098473106495;4735.810567577259;4870.95129048705;5002.574781143643;5337.973310133424;5367.114426119647;4050.675945924378;4561.566651168134;5209.947900520946;5401.432849550741;5488.036460311387;4457.5096739358;4744.430668319824;5257.6056795739705;5293.602979776455;5327.600429967845 08:51:10 INFO - PROCESS | 2770 | |5;yui.html;619.8109576579142;631.4380201620558;635.8442181665491;680.4012469027256;728.3481284250596;13936.442542298297;13957.302134893253;15282.923585382072;15296.923515382436;15299.541013769576;13723.588292351245;13815;13821.654458638539;13831.446742130303;13847.93076034621;895.552223888056;909.9044600316971;914.5930061122802;914.8810654614891;917.4266083697689;910.2081189365261;910.9498977556232;911.6809116809097;912.3112050401937;914.4467597103757;925.5418567808911;939.9577019034126;952.1383148250844;958.4249450329781;966.9903300966971;951.1392190068;951.5765484359463;952.8713623660786;954.5752140297606;957.0094951724931;707.4729326651626;707.6603230449413;708.6598432752256;709.1277728394052;709.7693249693871;12552.497900083985;14127;14996.25018749069;15252.466163684328;15276.465323713728;12754.61736147917;13808;14139.080959737585;14237.288135593179;14328.426862925471;10649.307794993378;11761.529538818522;12135.635930922086;12378.133530652767;12537.686557836036;4471.2845944649025;4494.797734101973;4510.819567217307;4516.390287311204;4533.297338912474;95271;95625.52187239089;98132.50933745356;98681.58569692947;99989.50005249926;10695.94652026735;11069.225154239126;11248.887511124947;11700.010998130338;11991.820122698167;23486.295411137693;23593.646095308584;24245.151419700225;24250.51498970046;24324.27027189187;7754.844903102019;7772.961135194288;7817.843643127106;7856.646450909723;8043.1554686757045;4019.8593049243723;4109.137081212919;4119.402686610425;4127.153933443637;4132.772697501606;3609.187932715118;3644.2711457708565;3719.75821571597;3720.3675375186285;3726.2361215950664;664.6344510519195;668.638934975108;684.5721424109931;687.2783577243974;688.2188715807595;598.2342601470125;603.8496663855362;651.8989556618732;657.6054367379629;685.9416949559244;9559.283053770856;9845.80308393828;9908.970745941064;10044.799104017879;10153.340032897986;546.7930587106591;559.4768891086846;597.680241071025;598.8113744170572;606.254307202135;3912.197999410114;4436.6672499562665;4769.73766442849;4781.474037855833;4806.069984525491;84689.88275293168;88361.67457488143;93330;94973;96213;69733.60532789316;83467.58266208752;84590;85020.57489712512;87800;52553.737231314364;73425.63287183637;74028.56442993673;74742.77829438758;74884.25115748991;8626.611802468778;9151.725448236564;9286.95356523213;9288.256939444964;9421.952890235505;43279.70160895178;47790;48612.46506791453;50230.748846255534;50359.496405036945;52009.47990520198;52866.47133528769;64358;66111;67890.3210967884;9643.324967252225;9686.56410461545;9695.418274903528;9861.506924653882;9958.053984871425;9982.800343993225;11873.940630296793;12023.819642705192;12069.818952715892;12470.251784892935;11550.48022838991;11717;12042.698932526757;12095.637130886087;12107.455164517442;11449.48477318506;11605.475731645445;11730.354830484406;11810.515897810339;12333.321667308384;11167.72080697989;11581;11729;12150.696232593902;12190.939045304716;6914.481413893877;8670.653173872974;8743.34424918121;8963.327750418614;9075.710295627749;6794.72821087151;7908.604569771604;8318.542480163649;9011.414258073204;9019.278457723498 08:51:10 INFO - PROCESS | 2770 | __end_tp_report 08:51:10 INFO - PROCESS | 2770 | __start_cc_report 08:51:10 INFO - PROCESS | 2770 | _x_x_mozilla_cycle_collect,3143 08:51:10 INFO - PROCESS | 2770 | __end_cc_report 08:51:10 INFO - PROCESS | 2770 | __startTimestamp1488127870144__endTimestamp 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | ------- Summary: start ------- 08:51:10 INFO - PROCESS | 2770 | Number of tests: 6 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | [#0] dojo.html Cycles:165 Average:5505.05 Median:5652.07 stddev:636.90 (11.3%) stddev-sans-first:600.29 08:51:10 INFO - PROCESS | 2770 | Values: 2722.8 3147.0 3183.6 3199.3 3221.5 5378.0 5454.3 5494.3 5559.1 5605.6 4621.7 5464.4 5502.7 5531.4 5591.1 4981.0 5010.5 5054.3 5062.0 5069.5 5144.7 5160.4 5171.8 5191.5 5193.4 4964.8 4998.3 5011.8 5042.2 5079.2 5054.2 5060.0 5061.3 5062.8 5064.5 5017.4 5019.4 5024.1 5028.0 5035.7 5436.2 5488.6 5489.9 5503.3 5512.3 5924.3 5928.1 5929.7 5933.2 5940.3 6055.2 6055.3 6106.1 6115.6 6144.8 5609.1 5613.8 5615.0 5621.7 5626.7 6287.5 6288.2 6288.5 6296.0 6308.0 5423.9 5436.7 5700.6 5719.8 5758.8 5943.2 5958.3 6006.8 6019.6 6086.6 4622.2 5777.3 5926.0 5929.8 5948.6 4959.3 5991.5 6002.9 6009.5 6009.8 4711.4 4751.6 4765.8 4782.5 4786.0 4248.7 4255.2 4300.2 4339.7 4370.5 5462.0 5462.9 5463.2 5464.6 5487.5 5872.3 5938.3 5960.3 5972.2 5979.5 5427.5 5489.8 5491.6 5501.9 5506.8 5181.4 5204.9 5253.7 5260.8 5261.2 5645.3 6229.2 6237.2 6264.8 6278.6 6086.8 6101.9 6105.3 6112.6 6115.1 6107.5 6125.3 6140.0 6273.7 6290.9 5848.8 5852.0 5855.8 5861.8 5864.6 5842.4 5845.7 5889.2 5909.3 5942.8 5384.5 5805.7 5831.9 5896.6 5901.1 5824.0 5829.9 5838.0 5860.4 5867.4 4752.3 5715.5 5728.8 5729.3 5740.1 5702.5 5759.0 5763.3 5811.7 5865.1 5652.1 5667.6 5675.4 5679.7 5683.4 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | [#1] ext.html Cycles:180 Average:50127.70 Median:33262.26 stddev:97124.75 (292.0%) stddev-sans-first:97326.47 08:51:10 INFO - PROCESS | 2770 | Values: 755.2 778.5 831.6 845.6 846.4 26186.3 27265.9 27369.5 37402.1 37710.6 38277.2 38467.8 38482.2 38482.7 38504.0 8934.4 9108.6 9119.2 9124.3 9129.0 7177.3 7348.0 8969.5 9091.5 10663.1 10724.1 10876.6 10886.7 10890.0 10925.4 10801.9 10854.1 10862.8 10867.8 10868.6 7128.4 7277.3 7296.3 10577.4 10642.4 26439.9 36352.0 36964.6 37112.8 38162.0 3515.8 3556.9 3561.3 3572.7 3637.7 22533.0 23290.3 24902.1 25226.9 26088.7 3005.9 3040.2 3103.3 3132.0 3135.4 314184.0 314816.0 315591.4 316437.0 316543.0 43099.8 43199.8 43300.9 43338.8 43421.6 36852.3 37513.4 37597.2 53099.0 54166.7 13082.2 13209.9 13290.8 13293.0 13321.5 11288.4 11346.8 11349.9 11354.8 11366.1 12750.9 12752.9 12774.6 12832.3 12882.4 5744.7 5758.5 5814.2 5815.5 5821.4 1052.4 1074.2 1083.8 1098.0 1141.2 24762.3 24838.0 24849.8 25028.0 25053.9 1043.9 1106.6 1155.4 1163.3 1205.5 12348.4 12776.6 13051.9 13085.6 13097.8 364334.0 410330.0 420263.0 421158.0 422190.0 50253.5 50583.5 50652.2 50660.5 50671.0 1323.0 1384.6 1391.5 1396.0 1401.8 22553.4 24573.8 24713.4 24906.6 24950.1 41582.0 41668.6 41681.4 41768.0 41771.0 372083.0 372207.0 372526.1 372976.1 373636.0 25891.0 31317.0 32236.2 35961.1 36684.6 37790.8 43515.0 43528.0 44389.1 44545.0 31597.7 34288.3 34657.1 36080.0 37027.0 35109.9 35225.6 35240.3 36447.8 36462.3 35645.9 36397.6 36463.8 36483.3 36546.9 29960.4 31485.8 31574.8 31630.8 31688.2 19184.8 23386.8 23656.1 23659.7 23925.9 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | [#2] jquery.html Cycles:180 Average:37929.03 Median:22187.17 stddev:114977.09 (518.2%) stddev-sans-first:115264.98 08:51:10 INFO - PROCESS | 2770 | Values: 335.4 354.5 378.3 396.3 416.7 12986.7 13741.7 20142.0 20453.3 21045.1 12087.0 12357.0 18716.7 21613.7 21925.8 5261.1 5427.2 5496.0 5563.7 5567.0 3599.6 3798.5 3803.8 5463.6 5574.6 3808.5 3830.1 5846.9 6131.1 6160.5 5273.4 5980.6 6140.6 6170.9 6217.4 6037.6 6072.3 6077.5 6085.8 6089.3 17215.0 22478.0 23079.5 24951.4 25471.6 2318.3 2515.8 2530.7 2616.3 2655.8 10000.6 10009.0 10298.3 10415.5 10461.6 1473.6 1794.2 1805.8 1825.9 1828.1 118905.0 124525.8 124572.8 125047.4 125511.0 11254.8 11646.9 11665.9 11692.1 11944.7 12479.7 13336.1 13998.2 13998.8 14534.4 7065.9 8328.1 8493.2 8608.4 8653.9 6667.5 8132.9 8142.2 8144.4 8327.3 4687.4 4774.6 4785.5 4877.4 4884.4 1710.0 1738.5 1951.9 2026.4 2032.3 998.6 1017.1 1034.0 1036.8 1071.5 21195.7 22049.0 22133.7 22240.7 22263.0 953.0 974.7 982.4 1082.3 1093.2 10850.2 11303.4 11473.4 11494.4 11520.8 682709.6 684604.0 686788.0 700199.0 700598.0 130044.0 136095.0 137988.8 138296.0 140373.0 990.5 995.3 1014.5 1016.8 1017.7 15187.5 16373.0 17866.6 18956.0 19279.2 55045.2 55848.4 56173.7 56989.0 57407.0 79879.6 80374.2 82902.0 83754.0 84342.6 8302.3 8379.4 8618.0 8651.1 8671.0 9448.0 10873.9 12554.5 12568.2 13441.0 11201.8 11319.0 11354.9 11645.5 11669.3 11025.8 11196.6 11226.6 11496.3 11600.2 11273.3 11461.7 11697.2 11837.7 11858.9 8412.6 8496.6 8559.3 8581.9 8595.0 8747.5 11085.0 11202.5 11458.2 11520.0 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | [#3] mootools.html Cycles:180 Average:5277.68 Median:2892.83 stddev:2918.55 (100.9%) stddev-sans-first:2911.72 08:51:10 INFO - PROCESS | 2770 | Values: 1337.9 1390.4 1457.3 1459.2 1462.0 7967.8 8396.0 8396.5 8405.8 8410.4 7967.0 8195.1 8278.0 8405.7 8409.3 2235.8 2281.3 2283.8 2284.3 2287.7 1947.5 2120.3 2164.9 2182.8 2182.9 1981.1 2012.3 2198.5 2269.9 2271.9 2234.6 2236.0 2236.0 2239.9 2245.8 2187.6 2281.5 2285.5 2285.6 2290.4 7708.6 8344.0 8437.5 8442.2 8444.3 4750.4 5161.3 5162.6 5163.6 5165.3 2831.4 2877.8 3261.2 3413.1 3461.6 1148.5 1185.5 1199.1 1230.8 1248.4 10457.3 10607.4 10702.1 10719.2 10778.7 4193.4 4332.4 4344.4 4424.6 4444.9 7124.3 8544.2 8571.3 8619.9 8642.1 4979.2 4985.0 4985.4 4985.8 5002.4 4502.8 4598.6 4651.5 4686.9 4688.2 3508.3 3557.6 3575.8 3603.8 3640.6 1125.9 1127.0 1132.7 1144.7 1144.8 571.5 640.0 683.9 753.6 785.1 5480.7 5527.9 5559.1 5570.7 5648.6 534.3 566.6 569.7 582.3 672.3 3634.0 3652.7 3757.7 3825.6 3858.6 8988.9 9311.3 9614.1 9730.8 9825.2 9856.4 9868.9 9872.0 9881.6 9882.1 7350.7 7581.7 7638.7 7692.3 7710.8 5610.1 5622.5 5717.8 5760.0 5827.7 8613.6 8800.3 9188.2 9202.8 9210.0 8799.8 9269.8 9271.2 9369.5 9372.3 6981.2 7098.2 7107.5 7117.1 7118.1 7604.8 7655.7 7660.8 7670.2 7676.8 5715.7 6838.4 6950.7 6986.7 7038.5 6735.1 6818.7 6821.7 6884.8 6891.5 5251.3 6366.8 6368.3 6379.7 6380.7 4108.8 4869.3 4976.5 5036.4 5059.2 4458.6 4854.4 4901.5 5166.4 5177.9 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | [#4] prototype.html Cycles:180 Average:4287.13 Median:4656.58 stddev:1303.53 (28.0%) stddev-sans-first:1272.21 08:51:10 INFO - PROCESS | 2770 | Values: 290.7 294.4 360.7 360.9 362.7 4514.5 4543.0 5073.6 5147.5 5234.6 4211.7 4559.6 4670.9 5108.5 5118.5 3532.7 3534.5 4057.2 4069.7 4089.9 3819.5 4065.2 4071.8 4079.2 4084.2 3341.6 3970.6 3983.1 3984.9 4023.6 3918.3 4004.2 4004.8 4015.8 4044.6 3588.3 3601.4 3611.9 3629.7 3636.6 3996.5 4218.4 5111.5 5120.2 5128.1 3733.9 3935.6 4459.6 4480.4 4482.6 4487.9 4921.0 5332.9 5467.3 5492.2 3126.7 3599.5 3666.9 3719.6 3725.6 5412.6 5522.8 5687.9 5700.7 5722.0 3239.6 3500.5 3643.9 3648.3 3668.7 3661.0 3717.9 3817.2 3822.8 3844.4 4410.3 4427.4 4428.0 4433.8 4484.5 5212.6 5393.8 5395.9 5425.7 5570.9 1072.5 1234.7 1244.2 1248.3 1262.3 768.3 788.8 841.6 843.0 862.1 4041.8 4579.3 4822.4 4834.7 4843.9 4842.4 5328.5 5359.9 5364.8 5378.5 4135.0 4154.6 4237.6 4635.9 4854.0 2251.8 2447.5 2469.8 2718.1 2758.5 4717.3 5020.8 5546.6 5586.4 5623.5 4843.7 5166.0 5512.8 5514.4 5540.6 4590.7 5515.1 5560.0 5606.3 5624.2 4541.7 4620.6 5300.5 5409.8 5411.2 4815.8 5097.6 5129.1 5258.8 5281.4 4953.3 5193.3 5298.7 5605.9 5653.5 3439.5 3586.6 3729.3 3758.0 3867.7 4642.3 4833.1 5001.2 5547.8 5580.2 4845.4 5121.9 5266.8 5405.2 5414.0 4433.9 5308.6 5395.6 5447.0 5573.6 4735.8 4871.0 5002.6 5338.0 5367.1 4050.7 4561.6 5209.9 5401.4 5488.0 4457.5 4744.4 5257.6 5293.6 5327.6 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - PROCESS | 2770 | [#5] yui.html Cycles:180 Average:18838.76 Median:613.03 stddev:26790.30 (4370.1%) stddev-sans-first:26830.53 08:51:10 INFO - PROCESS | 2770 | Values: 619.8 631.4 635.8 680.4 728.3 13936.4 13957.3 15282.9 15296.9 15299.5 13723.6 13815.0 13821.7 13831.4 13847.9 895.6 909.9 914.6 914.9 917.4 910.2 910.9 911.7 912.3 914.4 925.5 940.0 952.1 958.4 967.0 951.1 951.6 952.9 954.6 957.0 707.5 707.7 708.7 709.1 709.8 12552.5 14127.0 14996.3 15252.5 15276.5 12754.6 13808.0 14139.1 14237.3 14328.4 10649.3 11761.5 12135.6 12378.1 12537.7 4471.3 4494.8 4510.8 4516.4 4533.3 95271.0 95625.5 98132.5 98681.6 99989.5 10695.9 11069.2 11248.9 11700.0 11991.8 23486.3 23593.6 24245.2 24250.5 24324.3 7754.8 7773.0 7817.8 7856.6 8043.2 4019.9 4109.1 4119.4 4127.2 4132.8 3609.2 3644.3 3719.8 3720.4 3726.2 664.6 668.6 684.6 687.3 688.2 598.2 603.8 651.9 657.6 685.9 9559.3 9845.8 9909.0 10044.8 10153.3 546.8 559.5 597.7 598.8 606.3 3912.2 4436.7 4769.7 4781.5 4806.1 84689.9 88361.7 93330.0 94973.0 96213.0 69733.6 83467.6 84590.0 85020.6 87800.0 52553.7 73425.6 74028.6 74742.8 74884.3 8626.6 9151.7 9287.0 9288.3 9422.0 43279.7 47790.0 48612.5 50230.7 50359.5 52009.5 52866.5 64358.0 66111.0 67890.3 9643.3 9686.6 9695.4 9861.5 9958.1 9982.8 11873.9 12023.8 12069.8 12470.3 11550.5 11717.0 12042.7 12095.6 12107.5 11449.5 11605.5 11730.4 11810.5 12333.3 11167.7 11581.0 11729.0 12150.7 12190.9 6914.5 8670.7 8743.3 8963.3 9075.7 6794.7 7908.6 8318.5 9011.4 9019.3 08:51:10 INFO - PROCESS | 2770 | -------- Summary: end -------- 08:51:10 INFO - PROCESS | 2770 | 08:51:10 INFO - TEST-INFO | 2770: exit 0 08:51:10 INFO - TEST-OK | dromaeo_css | took 1120780ms 08:51:10 INFO - TEST-START | kraken 08:51:10 INFO - operating with platform_type : mac_ 08:51:10 INFO - Initialising browser for kraken test... 08:51:10 INFO - TEST-INFO | started process 2775 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpYNEhTB/profile http://localhost:49493/getInfo.html) 08:51:13 INFO - PROCESS | 2775 | __metrics Screen width/height:1600/1200 08:51:13 INFO - PROCESS | 2775 | colorDepth:24 08:51:13 INFO - PROCESS | 2775 | Browser inner width/height: 1024/689 08:51:13 INFO - PROCESS | 2775 | __metrics 08:51:13 INFO - PROCESS | 2775 | 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" 08:51:13 INFO - PROCESS | 2775 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:14 INFO - TEST-INFO | 2775: exit 0 08:51:14 INFO - Browser initialized. 08:51:14 INFO - Running cycle 1/1 for kraken test... 08:51:14 INFO - TEST-INFO | started process 2776 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpYNEhTB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:26 INFO - PROCESS | 2776 | RSS: Main: 230932480 08:51:26 INFO - PROCESS | 2776 | 08:51:29 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:30 INFO - PROCESS | 2776 | RSS: Main: 283942912 08:51:30 INFO - PROCESS | 2776 | 08:51:33 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-dft) 08:51:33 INFO - PROCESS | 2776 | RSS: Main: 287289344 08:51:33 INFO - PROCESS | 2776 | 08:51:37 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-fft) 08:51:37 INFO - PROCESS | 2776 | RSS: Main: 378175488 08:51:37 INFO - PROCESS | 2776 | 08:51:40 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49493/tests/kraken/driver.html?testName=audio-oscillator) 08:51:40 INFO - PROCESS | 2776 | RSS: Main: 280154112 08:51:40 INFO - PROCESS | 2776 | 08:51:42 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:51:43 INFO - PROCESS | 2776 | RSS: Main: 283725824 08:51:43 INFO - PROCESS | 2776 | 08:51:46 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49493/tests/kraken/driver.html?testName=imaging-darkroom) 08:51:47 INFO - PROCESS | 2776 | RSS: Main: 326037504 08:51:47 INFO - PROCESS | 2776 | 08:51:50 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49493/tests/kraken/driver.html?testName=imaging-desaturate) 08:51:51 INFO - PROCESS | 2776 | RSS: Main: 328691712 08:51:51 INFO - PROCESS | 2776 | 08:51:54 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49493/tests/kraken/driver.html?testName=json-parse-financial) 08:51:55 INFO - PROCESS | 2776 | RSS: Main: 324374528 08:51:55 INFO - PROCESS | 2776 | 08:51:56 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:51:57 INFO - PROCESS | 2776 | RSS: Main: 300355584 08:51:57 INFO - PROCESS | 2776 | 08:51:59 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:59 INFO - PROCESS | 2776 | RSS: Main: 304406528 08:51:59 INFO - PROCESS | 2776 | 08:52:02 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:02 INFO - PROCESS | 2776 | RSS: Main: 306438144 08:52:02 INFO - PROCESS | 2776 | 08:52:05 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:06 INFO - PROCESS | 2776 | RSS: Main: 322244608 08:52:06 INFO - PROCESS | 2776 | 08:52:09 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:52:10 INFO - PROCESS | 2776 | RSS: Main: 316047360 08:52:10 INFO - PROCESS | 2776 | 08:52:11 INFO - PROCESS | 2776 | Cycle 1(1): loaded http://localhost:49493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49493/tests/kraken/driver.html?testName=ai-astar) 08:52:12 INFO - PROCESS | 2776 | RSS: Main: 362102784 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | __start_tp_report 08:52:12 INFO - PROCESS | 2776 | _x_x_mozilla_page_load 08:52:12 INFO - PROCESS | 2776 | _x_x_mozilla_page_load_details 08:52:12 INFO - PROCESS | 2776 | |i|pagename|runs| 08:52:12 INFO - PROCESS | 2776 | |0;ai-astar;114;114;114;112;113;113;113;112;113;114 08:52:12 INFO - PROCESS | 2776 | |1;audio-beat-detection;148;142;122;152;114;112;112;129;169;113 08:52:12 INFO - PROCESS | 2776 | |2;audio-dft;187;187;186;185;188;184;187;181;185;183 08:52:12 INFO - PROCESS | 2776 | |3;audio-fft;81;62;78;116;86;53;78;83;101;81 08:52:12 INFO - PROCESS | 2776 | |4;audio-oscillator;89;72;90;69;71;71;87;68;68;85 08:52:12 INFO - PROCESS | 2776 | |5;imaging-gaussian-blur;88;87;89;88;89;88;88;87;88;88 08:52:12 INFO - PROCESS | 2776 | |6;imaging-darkroom;130;123;96;97;96;97;95;97;97;96 08:52:12 INFO - PROCESS | 2776 | |7;imaging-desaturate;86;134;131;90;89;87;88;85;85;86 08:52:12 INFO - PROCESS | 2776 | |8;json-parse-financial;64;61;61;63;61;64;63;63;60;64 08:52:12 INFO - PROCESS | 2776 | |9;json-stringify-tinderbox;60;61;71;71;65;62;63;64;64;65 08:52:12 INFO - PROCESS | 2776 | |10;stanford-crypto-aes;93;86;76;89;95;103;105;104;88;90 08:52:12 INFO - PROCESS | 2776 | |11;stanford-crypto-ccm;137;142;141;146;152;151;141;140;180;142 08:52:12 INFO - PROCESS | 2776 | |12;stanford-crypto-pbkdf2;172;181;175;229;229;178;168;170;172;172 08:52:12 INFO - PROCESS | 2776 | |13;stanford-crypto-sha256-iterative;67;68;67;67;67;67;64;66;65;66 08:52:12 INFO - PROCESS | 2776 | __end_tp_report 08:52:12 INFO - PROCESS | 2776 | __start_cc_report 08:52:12 INFO - PROCESS | 2776 | _x_x_mozilla_cycle_collect,2147 08:52:12 INFO - PROCESS | 2776 | __end_cc_report 08:52:12 INFO - PROCESS | 2776 | __startTimestamp1488127932221__endTimestamp 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | ------- Summary: start ------- 08:52:12 INFO - PROCESS | 2776 | Number of tests: 14 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.50 stddev:0.79 (0.7%) stddev-sans-first:0.78 08:52:12 INFO - PROCESS | 2776 | Values: 114.0 114.0 114.0 112.0 113.0 113.0 113.0 112.0 113.0 114.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:135.50 stddev:20.31 (15.0%) stddev-sans-first:20.63 08:52:12 INFO - PROCESS | 2776 | Values: 148.0 142.0 122.0 152.0 114.0 112.0 112.0 129.0 169.0 113.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.50 stddev:2.16 (1.2%) stddev-sans-first:2.20 08:52:12 INFO - PROCESS | 2776 | Values: 187.0 187.0 186.0 185.0 188.0 184.0 187.0 181.0 185.0 183.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#3] audio-fft Cycles:10 Average:81.90 Median:79.50 stddev:17.67 (22.2%) stddev-sans-first:18.73 08:52:12 INFO - PROCESS | 2776 | Values: 81.0 62.0 78.0 116.0 86.0 53.0 78.0 83.0 101.0 81.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.50 stddev:9.43 (12.0%) stddev-sans-first:8.94 08:52:12 INFO - PROCESS | 2776 | Values: 89.0 72.0 90.0 69.0 71.0 71.0 87.0 68.0 68.0 85.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:88.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 08:52:12 INFO - PROCESS | 2776 | Values: 88.0 87.0 89.0 88.0 89.0 88.0 88.0 87.0 88.0 88.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#6] imaging-darkroom Cycles:10 Average:102.40 Median:96.50 stddev:12.83 (13.3%) stddev-sans-first:8.90 08:52:12 INFO - PROCESS | 2776 | Values: 130.0 123.0 96.0 97.0 96.0 97.0 95.0 97.0 97.0 96.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:86.50 stddev:19.27 (22.3%) stddev-sans-first:20.09 08:52:12 INFO - PROCESS | 2776 | Values: 86.0 134.0 131.0 90.0 89.0 87.0 88.0 85.0 85.0 86.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:1.51 (2.4%) stddev-sans-first:1.48 08:52:12 INFO - PROCESS | 2776 | Values: 64.0 61.0 61.0 63.0 61.0 64.0 63.0 63.0 60.0 64.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:64.50 stddev:3.75 (5.8%) stddev-sans-first:3.59 08:52:12 INFO - PROCESS | 2776 | Values: 60.0 61.0 71.0 71.0 65.0 62.0 63.0 64.0 64.0 65.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:88.50 stddev:9.17 (10.4%) stddev-sans-first:9.73 08:52:12 INFO - PROCESS | 2776 | Values: 93.0 86.0 76.0 89.0 95.0 103.0 105.0 104.0 88.0 90.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:144.00 stddev:12.48 (8.7%) stddev-sans-first:12.68 08:52:12 INFO - PROCESS | 2776 | Values: 137.0 142.0 141.0 146.0 152.0 151.0 141.0 140.0 180.0 142.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:176.50 stddev:23.70 (13.4%) stddev-sans-first:24.70 08:52:12 INFO - PROCESS | 2776 | Values: 172.0 181.0 175.0 229.0 229.0 178.0 168.0 170.0 172.0 172.0 08:52:12 INFO - PROCESS | 2776 | 08:52:12 INFO - PROCESS | 2776 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.22 08:52:12 INFO - PROCESS | 2776 | Values: 67.0 68.0 67.0 67.0 67.0 67.0 64.0 66.0 65.0 66.0 08:52:12 INFO - PROCESS | 2776 | -------- Summary: end -------- 08:52:12 INFO - PROCESS | 2776 | 08:52:13 INFO - TEST-INFO | 2776: exit 0 08:52:13 INFO - TEST-OK | kraken | took 62302ms 08:52:13 INFO - SUITE-END | took 1183s 08:52:13 INFO - Completed test suite (00:19:43) 08:52:13 INFO - javascript benchmark 08:52:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6896.713291908515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2722.8366298022124, 3146.9685303146975, 3183.5702180205667, 3199.296154845934, 3221.5006673965545, 5377.973110134453, 5454.318210223722, 5494.285742853428, 5559.082751346028, 5605.607607467474, 4621.74580398078, 5464.4262352453015, 5502.669839809614, 5531.363893152288, 5591.077472217089, 4981.0, 5010.473900240468, 5054.267131265964, 5062.0, 5069.543741063304, 5144.6655967362185, 5160.406553246387, 5171.818986335479, 5191.506806853362, 5193.402758682762, 4964.801407943678, 4998.325226094467, 5011.799528018875, 5042.243663450475, 5079.161938280179, 5054.216596427523, 5059.974700126512, 5061.291419201315, 5062.772175252123, 5064.468230835783, 5017.422996355393, 5019.372578427697, 5024.070546948789, 5027.99440111979, 5035.697858128524, 5436.21174929637, 5488.643238189505, 5489.890202195974, 5503.284573005483, 5512.3385193777, 5924.289085309791, 5928.110783382484, 5929.703514824241, 5933.22868027158, 5940.31686356066, 6055.212822333113, 6055.303640081403, 6106.114613381079, 6115.6330620162935, 6144.815655530297, 5609.1305847593685, 5613.803516876889, 5615.0, 5621.7189140542805, 5626.662400255997, 6287.528435367366, 6288.151099601495, 6288.52836037299, 6295.96852015737, 6307.96846015767, 5423.945760542423, 5436.673799572039, 5700.629459085146, 5719.799807006735, 5758.798442054508, 5943.197668314722, 5958.314793798769, 6006.759729610767, 6019.578629495893, 6086.573939824259, 4622.19111655459, 5777.277840269966, 5926.0, 5929.762809487572, 5948.643081415129, 4959.2809042688605, 5991.4907232884825, 6002.909956350658, 6009.519238461, 6009.789657361971, 4711.3639658646325, 4751.619870410359, 4765.785539650724, 4782.473927867932, 4785.95214047862, 4248.702590818675, 4255.212785634668, 4300.225959327351, 4339.717918335298, 4370.541093185233, 5462.0, 5462.890742185214, 5463.2078348639225, 5464.644798088111, 5487.451254874561, 5872.295324560994, 5938.346781853993, 5960.254968128984, 5972.223610930551, 5979.491743201789, 5427.48438898304, 5489.752961116759, 5491.588130890199, 5501.862453438696, 5506.807261745819, 5181.3782346118705, 5204.9219261711105, 5253.711045892436, 5260.815871444481, 5261.237120617566, 5645.294338207724, 6229.1590635264565, 6237.220347456568, 6264.843378915563, 6278.623282602967, 6086.756529738761, 6101.908471373022, 6105.267367915879, 6112.572119951561, 6115.143879856734, 6107.450329470369, 6125.265974675844, 6140.0, 6273.686315684106, 6290.9056364155485, 5848.766049357978, 5852.005159123045, 5855.824325270249, 5861.824145275648, 5864.618799778001, 5842.415758424124, 5845.743165219498, 5889.175515427929, 5909.349971503045, 5942.7920022799, 5384.461553844584, 5805.738741756547, 5831.854203644943, 5896.616719913192, 5901.08533177341, 5824.0, 5829.912551311649, 5838.0, 5860.38465961085, 5867.413258674099, 4752.334673145693, 5715.457031581993, 5728.770849165987, 5729.28383952006, 5740.052891272893, 5702.486776190163, 5758.971205143947, 5763.337216220189, 5811.709414529172, 5865.090910908816, 5652.0674088774895, 5667.603267771381, 5675.375708672125, 5679.659220446786, 5683.403242659627], "value": 5463.6319777307035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.1730854714087, 778.4939789137062, 831.56342919967, 845.6194712379431, 846.4202021615198, 26186.34534136645, 27265.863670681643, 27369.45261094777, 37402.064948376305, 37710.622893771055, 38277.23445531094, 38467.807660961655, 38482.230355392945, 38482.653107141254, 38504.0, 8934.374593778455, 9108.635654573809, 9119.179273865351, 9124.270058395346, 9129.041450647654, 7177.318154775288, 7348.0, 8969.461832290051, 9091.499967501784, 10663.093637040862, 10724.14206863453, 10876.619318323861, 10886.673399797979, 10889.965453281926, 10925.399103049329, 10801.945990270038, 10854.077403420719, 10862.782744345148, 10867.83698244523, 10868.6195983141, 7128.429725621938, 7277.345038946521, 7296.343329100353, 10577.36535807854, 10642.414667193301, 26439.867800661064, 36352.0, 36964.63035369639, 37112.81443592792, 38162.0, 3515.8417871195857, 3556.9110772230642, 3561.269939662363, 3572.696320812734, 3637.6726094651344, 22533.0, 23290.3012909613, 24902.1284255052, 25226.873865630554, 26088.739112608822, 3005.9398812023855, 3040.2095455181593, 3103.301757104656, 3131.9821058155962, 3135.3885992231526, 314184.0, 314816.0, 315591.4220428906, 316437.0, 316542.9999999977, 43099.784501077294, 43199.784001079795, 43300.91747706269, 43338.78330608327, 43421.56578434238, 36852.262954740756, 37513.437298439996, 37597.248055039294, 53099.0, 54166.72916635471, 13082.215067096005, 13209.867901321057, 13290.800637990438, 13293.0, 13321.533746319026, 11288.379139147426, 11346.829797553044, 11349.943250283695, 11354.829677554842, 11366.09071274296, 12750.852423281949, 12752.93623531895, 12774.552890648783, 12832.29422381778, 12882.355882205853, 5744.741486633112, 5758.510526605201, 5814.186013958049, 5815.476607105381, 5821.417858214229, 1052.4369462337613, 1074.231924174219, 1083.772407794372, 1097.9780404392027, 1141.2068612314363, 24762.25713228606, 24837.967802634143, 24849.751502485105, 25028.0, 25053.87473062623, 1043.9247574997764, 1106.6443606582034, 1155.3760969076604, 1163.3427113680912, 1205.5412950330199, 12348.444320005621, 12776.55282065142, 13051.869481305255, 13085.607431777062, 13097.803532947013, 364334.0, 410330.0, 420263.0, 421158.0, 422190.0, 50253.49746502561, 50583.49416505861, 50652.240216396785, 50660.49339506632, 50670.98658026819, 1322.9603111906658, 1384.605387464588, 1391.5477469822551, 1396.043710058613, 1401.8387885393106, 22553.43616409603, 24573.754262457147, 24713.382165446008, 24906.626400603644, 24950.12674556382, 41582.0, 41668.58331416647, 41681.41504434449, 41768.0, 41771.0, 372083.0, 372207.0, 372526.1373693114, 372976.1351193227, 373636.0, 25891.0, 31317.0, 32236.197484452085, 35961.1009724759, 36684.633153668125, 37790.811045944596, 43515.0, 43528.0, 44389.11221775611, 44545.0, 31597.684023159472, 34288.314233714686, 34657.13357166091, 36080.0, 37027.0, 35109.946701598994, 35225.64774352326, 35240.295194096485, 36447.81776091103, 36462.27075458423, 35645.93062208138, 36397.63602364049, 36463.81768091142, 36483.27033459369, 36546.90359289225, 29960.400791983604, 31485.842570787914, 31574.842125790143, 31630.841845790623, 31688.207794805316, 19184.808151918303, 23386.76613233846, 23656.053757849495, 23659.71027501461, 23925.880370598035], "value": 17742.062520741914, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.44316434718365, 354.5090050280361, 378.33602028440083, 396.2550405238152, 416.6625033722685, 12986.74026519469, 13741.725165496684, 20142.0, 20453.284135055277, 21045.05297261623, 12087.033037357012, 12357.0, 18716.719249211274, 21613.67579486305, 21925.79408132552, 5261.1319132343115, 5427.158790387486, 5495.972520137414, 5563.721813909308, 5567.025770490169, 3599.6040435552068, 3798.5061941947506, 3803.8478460861534, 5463.617546771728, 5574.60977731559, 3808.5048943637426, 3830.080780612661, 5846.941530584682, 6131.141640170357, 6160.476359509447, 5273.446288139748, 5980.641161530322, 6140.631562106288, 6170.907436388457, 6217.440430361244, 6037.63774173551, 6072.2713274406915, 6077.483413909823, 6085.786997455068, 6089.269287685462, 17215.0, 22477.988490517964, 23079.53840923189, 24951.376215594573, 25471.61792573113, 2318.258157389636, 2515.8490490570625, 2530.734272901356, 2616.2674451153707, 2655.8273712208643, 10000.59997600095, 10009.0, 10298.330608510423, 10415.479226038668, 10461.581536738522, 1473.6389584551853, 1794.1836464408793, 1805.8194180581968, 1825.8813177143443, 1828.0859570214893, 118905.0, 124525.75474245232, 124572.75427245702, 125047.3747631256, 125511.0, 11254.831177532344, 11646.88353116458, 11665.941670291595, 11692.064634829194, 11944.701382465508, 12479.688007799878, 13336.133151345131, 13998.160110393206, 13998.790018149735, 14534.418623254951, 7065.92934070663, 8328.125546817497, 8493.150684931581, 8608.354373422018, 8653.870191947126, 6667.533272670964, 8132.9186708132165, 8142.226488483585, 8144.389170812095, 8327.250547450758, 4687.359424201502, 4774.570288673966, 4785.473597904228, 4877.3903262092235, 4884.3650325457465, 1709.9996502046442, 1738.5479775258523, 1951.897178094372, 2026.409268724074, 2032.2785411179113, 998.565623953568, 1017.1405162637559, 1033.9896601034043, 1036.7770929250248, 1071.5231721883688, 21195.68206476904, 22049.00779464928, 22133.66799498009, 22240.666390004164, 22263.0, 952.9952350238204, 974.7368210583103, 982.3712823792779, 1082.2802836113897, 1093.2292733622776, 10850.182341412681, 11303.378314192634, 11473.385883446688, 11494.367809770376, 11520.827187592193, 682709.5864520646, 684604.0, 686788.0, 700199.0, 700598.0, 130044.0, 136095.0, 137988.82122966467, 138296.02151720412, 140373.0, 990.5294984882123, 995.3430735714394, 1014.4724743133677, 1016.755978565139, 1017.6946915925192, 15187.481251874724, 16373.0, 17866.642667146843, 18956.0, 19279.22883084661, 55045.174322384395, 55848.441515585946, 56173.71913140408, 56989.0, 57407.0, 79879.60060199662, 80374.19625803667, 82902.0, 83754.0, 84342.57828710818, 8302.335813135056, 8379.413441059067, 8617.95691021541, 8651.091635378194, 8671.0, 9447.952760236154, 10873.891261087289, 12554.49782008746, 12568.183068100545, 13441.0, 11201.775964480828, 11319.0, 11354.886451135382, 11645.47595358194, 11669.299842009506, 11025.83461248098, 11196.552137914392, 11226.607068752555, 11496.31022138641, 11600.18798684084, 11273.323600583992, 11461.656150315504, 11697.23967942081, 11837.704057398634, 11858.881411185777, 8412.644459551335, 8496.617652205541, 8559.315254779727, 8581.871271931052, 8595.0, 8747.475151490931, 11085.0, 11202.49588768491, 11458.19792614509, 11520.020798232244], "value": 9526.998769893018, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.8795908368245, 1390.4368730664078, 1457.3004957620342, 1459.2484870291794, 1461.9853801461988, 7967.84064318714, 8395.950506186726, 8396.538190399526, 8405.789855253613, 8410.369222308329, 7967.0, 8195.098539160688, 8278.0, 8405.705800296992, 8409.327253819694, 2235.7876001779805, 2281.327008532487, 2283.828712846535, 2284.326123793485, 2287.7025986621716, 1947.51312171957, 2120.34269376493, 2164.8809315487642, 2182.8144607708364, 2182.9345119646355, 1981.0986001369415, 2012.3158126237076, 2198.5492973940386, 2269.8524595901295, 2271.9091236350528, 2234.575430668176, 2235.966460503094, 2236.0161528927383, 2239.876806775635, 2245.8091062259564, 2187.6062308784412, 2281.5208806150727, 2285.4857657027037, 2285.5885940530698, 2290.404494831339, 7708.614569271514, 8344.040435349953, 8437.451565648213, 8442.19799119089, 8444.282236009947, 4750.358701575277, 5161.251618515332, 5162.561182299509, 5163.612729045337, 5165.251038599382, 2831.419558990402, 2877.827330360185, 3261.1521004538754, 3413.112590726404, 3461.6192218855904, 1148.5405837664916, 1185.549491193341, 1199.1366216324232, 1230.8276841242234, 1248.4257241668843, 10457.26799124054, 10607.363558186533, 10702.143828493701, 10719.196060295431, 10778.730531736612, 4193.35003074524, 4332.415123958225, 4344.435223420998, 4424.60178583929, 4444.933326000112, 7124.287571242834, 8544.2310192083, 8571.314294856396, 8619.870701939475, 8642.135786421308, 4979.228219625964, 4984.975075124601, 4985.351904252424, 4985.825496107619, 5002.424721157041, 4502.842400515966, 4598.632109431237, 4651.511591282867, 4686.859394218179, 4688.249880019181, 3508.298340331944, 3557.616397014007, 3575.76757510761, 3603.837827297778, 3640.635936406391, 1125.870524889648, 1126.983095253572, 1132.6941725734005, 1144.6966553863233, 1144.7882141803793, 571.5027925704663, 639.9760383386637, 683.928187540311, 753.6269546574481, 785.0853755375039, 5480.698561579152, 5527.944720552823, 5559.054960656698, 5570.693611851387, 5648.576356773258, 534.3134072716541, 566.5637459156427, 569.7236840132532, 582.2605291280136, 672.3343889549311, 3633.9636603634153, 3652.726045546595, 3757.7369584129397, 3825.6365645263654, 3858.575556688762, 8988.910110898938, 9311.301652376098, 9614.134727874525, 9730.805383892282, 9825.213982881352, 9856.359336643096, 9868.865080515689, 9872.0, 9881.555330010167, 9882.11061004513, 7350.742724004741, 7581.696732130653, 7638.732644357421, 7692.307692307607, 7710.7686769397005, 5610.13042978339, 5622.522085622604, 5717.828465146053, 5760.0, 5827.737751801094, 8613.612387442456, 8800.295976321751, 9188.219001384958, 9202.815943680955, 9210.032946540518, 8799.824003519765, 9269.814603707753, 9271.165595096474, 9369.484678342757, 9372.297077719062, 6981.162260528566, 7098.219195888551, 7107.50247482692, 7117.110361204849, 7118.074650295428, 7604.8479030417975, 7655.6937722490475, 7660.846783064419, 7670.232976702285, 7676.769696909102, 5715.714214289352, 6838.384545390939, 6950.721971121301, 6986.685599147949, 7038.507304488637, 6735.124433823572, 6818.727250910107, 6821.727130914708, 6884.793456196322, 6891.517593768589, 5251.343582052244, 6366.808995730135, 6368.299487056327, 6379.74481020754, 6380.680965951591, 4108.77401742907, 4869.31829543857, 4976.477469865612, 5036.395632524121, 5059.165237735732, 4458.598726114666, 4854.393200849894, 4901.50984901507, 5166.4316925137455, 5177.922331165111], "value": 4211.454322514225, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.6904147083356, 294.3538932044164, 360.6988164882323, 360.90616439725665, 362.70439591732753, 4514.480834704002, 4542.977829988251, 5073.615400761641, 5147.485251474851, 5234.560495863635, 4211.726237794549, 4559.612432943196, 4670.90658186836, 5108.4636113208135, 5118.48815118488, 3532.717382609398, 3534.469829525579, 4057.249408859363, 4069.6540794032544, 4089.9182016359655, 3819.503464549605, 4065.1869626074754, 4071.819172439989, 4079.1637714268504, 4084.1627466369323, 3341.6274445143977, 3970.58308877568, 3983.103801644636, 3984.900377490557, 4023.6177563131455, 3918.314294998364, 4004.2191772604356, 4004.7797371144575, 4015.7992100394863, 4044.57531959143, 3588.2823435313044, 3601.441776524643, 3611.855525778966, 3629.691476224524, 3636.636336366342, 3996.4604778355133, 4218.4403252941665, 5111.463296353866, 5120.206368012964, 5128.1282182029145, 3733.925321493582, 3935.6457918787446, 4459.585010839278, 4480.372747815309, 4482.574155455227, 4487.865364039084, 4920.950790492121, 5332.880095180017, 5467.289252397163, 5492.231087647732, 3126.718595326409, 3599.4600809878466, 3666.871659491905, 3719.6280371962857, 3725.62743725628, 5412.648177868426, 5522.834314970557, 5687.886242275132, 5700.660344818964, 5722.027255366597, 3239.61124665037, 3500.492428597839, 3643.854245830137, 3648.325059863934, 3668.724845636588, 3661.0, 3717.925641487155, 3817.1602247505502, 3822.827972741233, 3844.442555829389, 4410.29435290352, 4427.358033085249, 4427.955720442818, 4433.7831084457775, 4484.461864576207, 5212.582993360522, 5393.78424863009, 5395.8938417624295, 5425.674459532441, 5570.888582228333, 1072.522727386321, 1234.6788935838567, 1244.234795600699, 1248.3071895098308, 1262.2868079535217, 768.3238749900164, 788.8128366807917, 841.5834162089805, 843.0431460292612, 862.0732712334266, 4041.818118184689, 4579.290210017452, 4822.397200349957, 4834.661573689878, 4843.854684359475, 4842.394700662418, 5328.547073498796, 5359.936424476527, 5364.8122315718765, 5378.489043540935, 4135.0, 4154.584541545882, 4237.555056719062, 4635.860924172281, 4854.004928989549, 2251.8085962693353, 2447.49826285611, 2469.839175587471, 2718.075854209578, 2758.475889580973, 4717.339572459859, 5020.849374518771, 5546.619778814544, 5586.385497595261, 5623.465770751772, 4843.716415149988, 5165.992631436834, 5512.779488820483, 5514.36584792754, 5540.556755459554, 4590.724556526619, 5515.062439385234, 5559.969022458766, 5606.327240731139, 5624.200255918149, 4541.704789188692, 4620.607248383925, 5300.522952934254, 5409.83770486886, 5411.242426060433, 4815.783289751899, 5097.643164978416, 5129.101882569349, 5258.763355648928, 5281.445448227881, 4953.2570114483115, 5193.324867767166, 5298.675331167208, 5605.915911261252, 5653.547716182778, 3439.466882633196, 3586.6054733979763, 3729.291434627412, 3757.981210094041, 3867.7099217559357, 4642.259152817694, 4833.10587541306, 5001.24981252815, 5547.7780888764, 5580.218769372373, 4845.370101886732, 5121.8975620486635, 5266.762995665128, 5405.216278627965, 5413.998410294108, 4433.911321773611, 5308.628396012242, 5395.622306438511, 5446.972765136149, 5573.6098473106495, 4735.810567577259, 4870.95129048705, 5002.574781143643, 5337.973310133424, 5367.114426119647, 4050.675945924378, 4561.566651168134, 5209.947900520946, 5401.432849550741, 5488.036460311387, 4457.5096739358, 4744.430668319824, 5257.6056795739705, 5293.602979776455, 5327.600429967845], "value": 3881.514194995418, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.8109576579142, 631.4380201620558, 635.8442181665491, 680.4012469027256, 728.3481284250596, 13936.442542298297, 13957.302134893253, 15282.923585382072, 15296.923515382436, 15299.541013769576, 13723.588292351245, 13815.0, 13821.654458638539, 13831.446742130303, 13847.93076034621, 895.552223888056, 909.9044600316971, 914.5930061122802, 914.8810654614891, 917.4266083697689, 910.2081189365261, 910.9498977556232, 911.6809116809097, 912.3112050401937, 914.4467597103757, 925.5418567808911, 939.9577019034126, 952.1383148250844, 958.4249450329781, 966.9903300966971, 951.1392190068, 951.5765484359463, 952.8713623660786, 954.5752140297606, 957.0094951724931, 707.4729326651626, 707.6603230449413, 708.6598432752256, 709.1277728394052, 709.7693249693871, 12552.497900083985, 14127.0, 14996.25018749069, 15252.466163684328, 15276.465323713728, 12754.61736147917, 13808.0, 14139.080959737585, 14237.288135593179, 14328.426862925471, 10649.307794993378, 11761.529538818522, 12135.635930922086, 12378.133530652767, 12537.686557836036, 4471.2845944649025, 4494.797734101973, 4510.819567217307, 4516.390287311204, 4533.297338912474, 95271.0, 95625.52187239089, 98132.50933745356, 98681.58569692947, 99989.50005249926, 10695.94652026735, 11069.225154239126, 11248.887511124947, 11700.010998130338, 11991.820122698167, 23486.295411137693, 23593.646095308584, 24245.151419700225, 24250.51498970046, 24324.27027189187, 7754.844903102019, 7772.961135194288, 7817.843643127106, 7856.646450909723, 8043.1554686757045, 4019.8593049243723, 4109.137081212919, 4119.402686610425, 4127.153933443637, 4132.772697501606, 3609.187932715118, 3644.2711457708565, 3719.75821571597, 3720.3675375186285, 3726.2361215950664, 664.6344510519195, 668.638934975108, 684.5721424109931, 687.2783577243974, 688.2188715807595, 598.2342601470125, 603.8496663855362, 651.8989556618732, 657.6054367379629, 685.9416949559244, 9559.283053770856, 9845.80308393828, 9908.970745941064, 10044.799104017879, 10153.340032897986, 546.7930587106591, 559.4768891086846, 597.680241071025, 598.8113744170572, 606.254307202135, 3912.197999410114, 4436.6672499562665, 4769.73766442849, 4781.474037855833, 4806.069984525491, 84689.88275293168, 88361.67457488143, 93330.0, 94973.0, 96213.0, 69733.60532789316, 83467.58266208752, 84590.0, 85020.57489712512, 87800.0, 52553.737231314364, 73425.63287183637, 74028.56442993673, 74742.77829438758, 74884.25115748991, 8626.611802468778, 9151.725448236564, 9286.95356523213, 9288.256939444964, 9421.952890235505, 43279.70160895178, 47790.0, 48612.46506791453, 50230.748846255534, 50359.496405036945, 52009.47990520198, 52866.47133528769, 64358.0, 66111.0, 67890.3210967884, 9643.324967252225, 9686.56410461545, 9695.418274903528, 9861.506924653882, 9958.053984871425, 9982.800343993225, 11873.940630296793, 12023.819642705192, 12069.818952715892, 12470.251784892935, 11550.48022838991, 11717.0, 12042.698932526757, 12095.637130886087, 12107.455164517442, 11449.48477318506, 11605.475731645445, 11730.354830484406, 11810.515897810339, 12333.321667308384, 11167.72080697989, 11581.0, 11729.0, 12150.696232593902, 12190.939045304716, 6914.481413893877, 8670.653173872974, 8743.34424918121, 8963.327750418614, 9075.710295627749, 6794.72821087151, 7908.604569771604, 8318.542480163649, 9011.414258073204, 9019.278457723498], "value": 7127.388824, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1493.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 112.0, 113.0, 113.0, 113.0, 112.0, 113.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 142.0, 122.0, 152.0, 114.0, 112.0, 112.0, 129.0, 169.0, 113.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 186.0, 185.0, 188.0, 184.0, 187.0, 181.0, 185.0, 183.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 78.0, 116.0, 86.0, 53.0, 78.0, 83.0, 101.0, 81.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 72.0, 90.0, 69.0, 71.0, 71.0, 87.0, 68.0, 68.0, 85.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 88.0, 89.0, 88.0, 88.0, 87.0, 88.0, 88.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 123.0, 96.0, 97.0, 96.0, 97.0, 95.0, 97.0, 97.0, 96.0], "value": 102.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 134.0, 131.0, 90.0, 89.0, 87.0, 88.0, 85.0, 85.0, 86.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 63.0, 61.0, 64.0, 63.0, 63.0, 60.0, 64.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 71.0, 71.0, 65.0, 62.0, 63.0, 64.0, 64.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 86.0, 76.0, 89.0, 95.0, 103.0, 105.0, 104.0, 88.0, 90.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 142.0, 141.0, 146.0, 152.0, 151.0, 141.0, 140.0, 180.0, 142.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 181.0, 175.0, 229.0, 229.0, 178.0, 168.0, 170.0, 172.0, 172.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 64.0, 66.0, 65.0, 66.0], "value": 66.4, "unit": "score"}]}]} 08:52:13 INFO - Return code: 0 08:52:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:52:13 ERROR - # TBPL SUCCESS # 08:52:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:52:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:52:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:52:13 INFO - 2017-02-26 08:52:13.557 screenresolution[2780:56004] starting screenresolution argv=screenresolution get 08:52:13 INFO - 2017-02-26 08:52:13.559 screenresolution[2780:56004] Display 0: 1600x1200x32@60 08:52:13 INFO - 2017-02-26 08:52:13.565 screenresolution[2781:56008] starting screenresolution argv=screenresolution list 08:52:13 INFO - Available Modes on Display 0 08:52:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:52:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:52:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:52:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:52:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:52:13 INFO - Intel Iris: 08:52:13 INFO - Chipset Model: Intel Iris 08:52:13 INFO - Type: GPU 08:52:13 INFO - Bus: Built-In 08:52:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:52:13 INFO - Vendor: Intel (0x8086) 08:52:13 INFO - Device ID: 0x0a2e 08:52:13 INFO - Revision ID: 0x0009 08:52:13 INFO - Displays: 08:52:13 INFO - Display: 08:52:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:52:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:52:13 INFO - Display Serial Number: 1600x1200 60 08:52:13 INFO - Main Display: Yes 08:52:13 INFO - Mirror: Off 08:52:13 INFO - Online: Yes 08:52:13 INFO - Rotation: Supported 08:52:13 INFO - Return code: 0 08:52:13 INFO - Running post-action listener: _resource_record_post_action 08:52:13 INFO - [mozharness: 2017-02-26 16:52:13.803515Z] Finished run-tests step (success) 08:52:13 INFO - Running post-run listener: _resource_record_post_run 08:52:13 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 1966080; Write bytes: 514905088; Read time: 12; Write time: 1624 08:52:13 INFO - TinderboxPrint: CPU usage
53.6% 08:52:13 INFO - TinderboxPrint: I/O read bytes / time
1,966,080 / 12 08:52:13 INFO - TinderboxPrint: I/O write bytes / time
514,905,088 / 1,624 08:52:13 INFO - TinderboxPrint: CPU idle
2,236.0 (46.4%) 08:52:13 INFO - TinderboxPrint: CPU system
962.1 (20.0%) 08:52:13 INFO - TinderboxPrint: CPU user
1,619.7 (33.6%) 08:52:13 INFO - TinderboxPrint: Swap in / out
211,742,720 / 0 08:52:13 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203901440; Write bytes: 201715712; Read time: 15586; Write time: 418 08:52:13 INFO - run-tests - Wall time: 1184s; CPU: 54.0%; Read bytes: 1966080; Write bytes: 303436800; Read time: 12; Write time: 1175 08:52:14 INFO - Running post-run listener: _upload_blobber_files 08:52:14 INFO - Blob upload gear active. 08:52:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:52:14 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 08:52:14 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'] 08:52:14 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 08:52:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:52:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:52:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:52:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:16 INFO - (blobuploader) - INFO - Done attempting. 08:52:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:52:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:52:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:16 INFO - (blobuploader) - INFO - Done attempting. 08:52:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:52:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:52:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:18 INFO - (blobuploader) - INFO - Done attempting. 08:52:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:52:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:52:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:52:20 INFO - (blobuploader) - INFO - Done attempting. 08:52:20 INFO - (blobuploader) - INFO - Iteration through files over. 08:52:20 INFO - Return code: 0 08:52:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:52:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:52:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad328b0ab2a4d24935161192a10f33123b6a28ea4630ea79109c29a2411a52354b0bd6b33ac600b341efc63748f46e5dab9fc22f1906f7fad7f76f1e321e821", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/605bd1f7d602aaa954f939d8f2ed07aecb82bebe76f46b764e323c38ed002c0b24ae312f23c27c5de05363e6a6b1333c5d57710006e0b33b3fcc9ee0492ea2d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d61dea692217c2f5709aadea8c07e816406d7a5b5399f614764f88ddda87933ca7fd2fa60cb7c608ca825e8ab0258cb87025dc7c1f2b4ffc2d74d9a6fbf800e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d31ecbf7b804f2ad7b594892268aefc9e5a9892122e30a8ba69430b36ffb735fffa2cca1d470188dfc0863b179595a6c91d5c17d341c686fe278bbe30df109bd"} 08:52:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:52:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:52:20 INFO - Contents: 08:52:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad328b0ab2a4d24935161192a10f33123b6a28ea4630ea79109c29a2411a52354b0bd6b33ac600b341efc63748f46e5dab9fc22f1906f7fad7f76f1e321e821", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/605bd1f7d602aaa954f939d8f2ed07aecb82bebe76f46b764e323c38ed002c0b24ae312f23c27c5de05363e6a6b1333c5d57710006e0b33b3fcc9ee0492ea2d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d61dea692217c2f5709aadea8c07e816406d7a5b5399f614764f88ddda87933ca7fd2fa60cb7c608ca825e8ab0258cb87025dc7c1f2b4ffc2d74d9a6fbf800e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d31ecbf7b804f2ad7b594892268aefc9e5a9892122e30a8ba69430b36ffb735fffa2cca1d470188dfc0863b179595a6c91d5c17d341c686fe278bbe30df109bd"} 08:52:20 INFO - Running post-run listener: copy_logs_to_upload_dir 08:52:20 INFO - Copying logs to upload dir... 08:52:20 INFO - mkdir: /builds/slave/test/build/upload/logs 08:52:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.832834 ========= master_lag: 2.20 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-02-26 08:52:22.684854) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-26 08:52:22.693382) ========= 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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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/aad328b0ab2a4d24935161192a10f33123b6a28ea4630ea79109c29a2411a52354b0bd6b33ac600b341efc63748f46e5dab9fc22f1906f7fad7f76f1e321e821", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/605bd1f7d602aaa954f939d8f2ed07aecb82bebe76f46b764e323c38ed002c0b24ae312f23c27c5de05363e6a6b1333c5d57710006e0b33b3fcc9ee0492ea2d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d61dea692217c2f5709aadea8c07e816406d7a5b5399f614764f88ddda87933ca7fd2fa60cb7c608ca825e8ab0258cb87025dc7c1f2b4ffc2d74d9a6fbf800e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d31ecbf7b804f2ad7b594892268aefc9e5a9892122e30a8ba69430b36ffb735fffa2cca1d470188dfc0863b179595a6c91d5c17d341c686fe278bbe30df109bd"} build_url:https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011554 build_url: 'https://queue.taskcluster.net/v1/task/Dd3REXnaSSCk-8bXFsK4ZQ/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/aad328b0ab2a4d24935161192a10f33123b6a28ea4630ea79109c29a2411a52354b0bd6b33ac600b341efc63748f46e5dab9fc22f1906f7fad7f76f1e321e821", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/605bd1f7d602aaa954f939d8f2ed07aecb82bebe76f46b764e323c38ed002c0b24ae312f23c27c5de05363e6a6b1333c5d57710006e0b33b3fcc9ee0492ea2d4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d61dea692217c2f5709aadea8c07e816406d7a5b5399f614764f88ddda87933ca7fd2fa60cb7c608ca825e8ab0258cb87025dc7c1f2b4ffc2d74d9a6fbf800e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d31ecbf7b804f2ad7b594892268aefc9e5a9892122e30a8ba69430b36ffb735fffa2cca1d470188dfc0863b179595a6c91d5c17d341c686fe278bbe30df109bd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-26 08:52:22.734860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 08:52:22.735173) ========= 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.feZ0ka7vSc/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.WoPtRQMZVR/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.005275 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-26 08:52:22.787455) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-26 08:52:22.787749) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-26 08:52:22.790104) ========= ========= Total master_lag: 2.35 =========